You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/02/08 00:41:55 UTC
[JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build #
1664 - Still unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1664/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation
Error Message:
4 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation: 1) Thread[id=840, name=jetty-launcher-130-thread-1-EventThread, state=WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502) 2) Thread[id=837, name=jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063) 3) Thread[id=838, name=jetty-launcher-130-thread-2-EventThread, state=WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502) 4) Thread[id=839, name=jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:
1) Thread[id=840, name=jetty-launcher-130-thread-1-EventThread, state=WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
2) Thread[id=837, name=jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at java.lang.Thread.sleep(Native Method)
at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
3) Thread[id=838, name=jetty-launcher-130-thread-2-EventThread, state=WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
4) Thread[id=839, name=jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at java.lang.Thread.sleep(Native Method)
at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
at __randomizedtesting.SeedInfo.seed([F4F19B2AE1EB4A26]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=837, name=jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063) 2) Thread[id=839, name=jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=837, name=jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at java.lang.Thread.sleep(Native Method)
at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
2) Thread[id=839, name=jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at java.lang.Thread.sleep(Native Method)
at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
at __randomizedtesting.SeedInfo.seed([F4F19B2AE1EB4A26]:0)
Build Log:
[...truncated 12011 lines...]
[junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_F4F19B2AE1EB4A26-001/init-core-data-001
[junit4] 2> 64764 WARN (SUITE-TestSolrCloudWithSecureImpersonation-seed#[F4F19B2AE1EB4A26]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9
[junit4] 2> 64767 INFO (SUITE-TestSolrCloudWithSecureImpersonation-seed#[F4F19B2AE1EB4A26]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 64773 INFO (SUITE-TestSolrCloudWithSecureImpersonation-seed#[F4F19B2AE1EB4A26]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 64773 INFO (SUITE-TestSolrCloudWithSecureImpersonation-seed#[F4F19B2AE1EB4A26]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 64960 WARN (SUITE-TestSolrCloudWithSecureImpersonation-seed#[F4F19B2AE1EB4A26]-worker) [ ] o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
[junit4] 2> 65465 INFO (SUITE-TestSolrCloudWithSecureImpersonation-seed#[F4F19B2AE1EB4A26]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_F4F19B2AE1EB4A26-001/tempDir-001
[junit4] 2> 65466 INFO (SUITE-TestSolrCloudWithSecureImpersonation-seed#[F4F19B2AE1EB4A26]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 65466 INFO (Thread-185) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 65466 INFO (Thread-185) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 65484 ERROR (Thread-185) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 65566 INFO (SUITE-TestSolrCloudWithSecureImpersonation-seed#[F4F19B2AE1EB4A26]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:43127
[junit4] 2> 65578 INFO (zkConnectionManagerCallback-133-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65607 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 65612 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 65616 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 65622 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 65622 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 65624 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 65624 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 65625 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 65625 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@78b303ea{/solr,null,AVAILABLE}
[junit4] 2> 65647 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3d5cf081{/solr,null,AVAILABLE}
[junit4] 2> 65655 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7dca1cbe{HTTP/1.1,[http/1.1]}{127.0.0.1:58994}
[junit4] 2> 65656 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3dac27c9{HTTP/1.1,[http/1.1]}{127.0.0.1:55838}
[junit4] 2> 65656 INFO (jetty-launcher-130-thread-1) [ ] o.e.j.s.Server Started @68437ms
[junit4] 2> 65656 INFO (jetty-launcher-130-thread-2) [ ] o.e.j.s.Server Started @68437ms
[junit4] 2> 65656 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58994}
[junit4] 2> 65656 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55838}
[junit4] 2> 65657 ERROR (jetty-launcher-130-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 65657 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 65657 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 65657 ERROR (jetty-launcher-130-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 65657 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 65657 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 65657 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-07T23:36:35.532Z
[junit4] 2> 65657 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 65657 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 65658 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-07T23:36:35.533Z
[junit4] 2> 65685 INFO (zkConnectionManagerCallback-137-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65703 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 65710 INFO (zkConnectionManagerCallback-135-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65714 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 65736 INFO (jetty-launcher-130-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43127/solr
[junit4] 2> 65744 INFO (jetty-launcher-130-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43127/solr
[junit4] 2> 65756 INFO (zkConnectionManagerCallback-145-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65756 INFO (zkConnectionManagerCallback-142-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65772 INFO (zkConnectionManagerCallback-147-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65778 INFO (zkConnectionManagerCallback-149-thread-1-processing-n:127.0.0.1:55838_solr) [n:127.0.0.1:55838_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65907 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 65908 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58994_solr
[junit4] 2> 65909 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.c.Overseer Overseer (id=72159992018436101-127.0.0.1:58994_solr-n_0000000000) starting
[junit4] 2> 65919 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58994_solr
[junit4] 2> 65961 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 65989 WARN (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder org.apache.solr.client.solrj.impl.Krb5HttpClientBuilder is configured without specifying system property 'java.security.auth.login.config'
[junit4] 2> 66038 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 66046 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.s.KerberosPlugin Params: {solr.kerberos.impersonator.user.noGroups.hosts=*, solr.kerberos.impersonator.user.bogusGroup.groups=__some_bogus_group, token.valid=30, cookie.domain=127.0.0.1, solr.kerberos.impersonator.user.anyHostAnyUser.groups=*, solr.kerberos.impersonator.user.bogusGroup.hosts=*, solr.kerberos.impersonator.user.anyHostAnyUser.hosts=*, token.validity=36000, solr.kerberos.impersonator.user.noHosts.groups=*, type=kerberos, solr.kerberos.impersonator.user.localHostAnyGroup.groups=*, delegation-token.token-kind=solr-dt, solr.kerberos.impersonator.user.wrongHost.hosts=1.1.1.1.1.1, cookie.path=/, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, solr.kerberos.impersonator.user.localHostAnyGroup.hosts=serv1-vm3,localhost,127.0.0.1, signer.secret.provider.zookeeper.path=/token, solr.kerberos.impersonator.user.anyHostUsersGroup.groups=staff, zk-dt-secret-manager.enable=true, solr.kerberos.impersonator.user.wrongHost.groups=*, kerberos.name.rules=DEFAULT, signer.secret.provider=zookeeper, solr.kerberos.impersonator.user.anyHostUsersGroup.hosts=*}
[junit4] 2> 66252 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 66257 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 66263 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55838_solr
[junit4] 2> 66265 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 66268 INFO (zkCallback-148-thread-1-processing-n:127.0.0.1:55838_solr) [n:127.0.0.1:55838_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 66374 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 66375 WARN (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder org.apache.solr.client.solrj.impl.Krb5HttpClientBuilder is configured without specifying system property 'java.security.auth.login.config'
[junit4] 2> 66379 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.s.s.KerberosPlugin Params: {solr.kerberos.impersonator.user.noGroups.hosts=*, solr.kerberos.impersonator.user.bogusGroup.groups=__some_bogus_group, token.valid=30, cookie.domain=127.0.0.1, solr.kerberos.impersonator.user.anyHostAnyUser.groups=*, solr.kerberos.impersonator.user.bogusGroup.hosts=*, solr.kerberos.impersonator.user.anyHostAnyUser.hosts=*, token.validity=36000, solr.kerberos.impersonator.user.noHosts.groups=*, type=kerberos, solr.kerberos.impersonator.user.localHostAnyGroup.groups=*, delegation-token.token-kind=solr-dt, solr.kerberos.impersonator.user.wrongHost.hosts=1.1.1.1.1.1, cookie.path=/, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, solr.kerberos.impersonator.user.localHostAnyGroup.hosts=serv1-vm3,localhost,127.0.0.1, signer.secret.provider.zookeeper.path=/token, solr.kerberos.impersonator.user.anyHostUsersGroup.groups=staff, zk-dt-secret-manager.enable=true, solr.kerberos.impersonator.user.wrongHost.groups=*, kerberos.name.rules=DEFAULT, signer.secret.provider=zookeeper, solr.kerberos.impersonator.user.anyHostUsersGroup.hosts=*}
[junit4] 2> 66379 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 66385 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 66512 INFO (jetty-launcher-130-thread-1-EventThread) [n:127.0.0.1:58994_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 66514 INFO (jetty-launcher-130-thread-2-EventThread) [n:127.0.0.1:55838_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 67060 WARN (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder org.apache.solr.client.solrj.impl.Krb5HttpClientBuilder is configured without specifying system property 'java.security.auth.login.config'
[junit4] 2> 67061 WARN (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder org.apache.solr.client.solrj.impl.Krb5HttpClientBuilder is configured without specifying system property 'java.security.auth.login.config'
[junit4] 2> 67217 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55838.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47f16cae
[junit4] 2> 67225 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55838.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47f16cae
[junit4] 2> 67226 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55838.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47f16cae
[junit4] 2> 67230 INFO (jetty-launcher-130-thread-2) [n:127.0.0.1:55838_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_F4F19B2AE1EB4A26-001/tempDir-001/node2/.
[junit4] 2> 67337 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58994.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47f16cae
[junit4] 2> 67353 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58994.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47f16cae
[junit4] 2> 67353 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58994.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47f16cae
[junit4] 2> 67355 INFO (jetty-launcher-130-thread-1) [n:127.0.0.1:58994_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_F4F19B2AE1EB4A26-001/tempDir-001/node1/.
[junit4] 2> 67488 INFO (zkConnectionManagerCallback-155-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 67526 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateAnyHostAnyUser-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateAnyHostAnyUser
[junit4] 2> 67642 INFO (qtp744172632-718) [n:127.0.0.1:55838_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params doAs=bar&action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 67642 INFO (qtp744172632-718) [n:127.0.0.1:55838_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={doAs=bar&action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 67682 WARN (TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateAnyHostAnyUser-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=anyHostAnyUser&p=anyHostAnyUser&t=test&e=1518082597476&s=Q+vhv+C/4uTJBpe03N5gtv6VS3M="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:37 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:37 GMT
[junit4] 2> 67682 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateAnyHostAnyUser-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateAnyHostAnyUser
[junit4] 2> 67706 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigGroups-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNoConfigGroups
[junit4] 2> 69196 WARN (TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigGroups-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=noGroups&p=noGroups&t=test&e=1518082597583&s=uo+oyW4NTPNthvhcID7bCTM1t38="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:37 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:37 GMT
[junit4] 2> 69206 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigGroups-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNoConfigGroups
[junit4] 2> 69241 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidRemote-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyInvalidRemote
[junit4] 2> 69266 WARN (TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidRemote-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=localHostAnyGroup&p=localHostAnyGroup&t=test&e=1518082599117&s=c0TmKGUWvzh9Psn945JtICp6iZQ="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:39 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:39 GMT
[junit4] 2> 69266 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidRemote-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyInvalidRemote
[junit4] 2> 69311 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyWrongHost-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyWrongHost
[junit4] 2> 73513 WARN (TEST-TestSolrCloudWithSecureImpersonation.testProxyWrongHost-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=wrongHost&p=wrongHost&t=test&e=1518082599187&s=5Oi2tVX21d6yvBHVjdgPX879DUE="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:39 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:39 GMT
[junit4] 2> 73514 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyWrongHost-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyWrongHost
[junit4] 2> 73579 INFO (TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testForwarding
[junit4] 2> 73591 INFO (zkConnectionManagerCallback-158-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 75758 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1005d216341000a, likely client has closed socket
[junit4] 2> 75772 INFO (zkConnectionManagerCallback-161-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 75777 INFO (TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 75787 INFO (TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43127/solr ready
[junit4] 2> 75797 INFO (qtp744172632-722) [n:127.0.0.1:55838_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=1&collection.configName=conf1&maxShardsPerNode=1&name=forwardingCollection&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 75818 INFO (OverseerThreadFactory-278-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection forwardingCollection
[junit4] 2> 75955 INFO (OverseerStateUpdate-72159992018436101-127.0.0.1:58994_solr-n_0000000000) [n:127.0.0.1:58994_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"forwardingCollection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"forwardingCollection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:58994/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 76196 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=forwardingCollection_shard1_replica_n1&action=CREATE&numShards=1&collection=forwardingCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 76196 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 76338 INFO (zkCallback-146-thread-2-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/forwardingCollection/state.json] for collection [forwardingCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 76339 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/forwardingCollection/state.json] for collection [forwardingCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 77285 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 77303 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.s.IndexSchema [forwardingCollection_shard1_replica_n1] Schema name=test
[junit4] 2> 77686 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 77712 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'forwardingCollection_shard1_replica_n1' using configuration from collection forwardingCollection, trusted=true
[junit4] 2> 77713 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58994.solr.core.forwardingCollection.shard1.replica_n1' (registry 'solr.core.forwardingCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47f16cae
[junit4] 2> 77713 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 77713 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.SolrCore [[forwardingCollection_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_F4F19B2AE1EB4A26-001/tempDir-001/node1/forwardingCollection_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_F4F19B2AE1EB4A26-001/tempDir-001/node1/./forwardingCollection_shard1_replica_n1/data/]
[junit4] 2> 77717 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=26, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7437137153327383]
[junit4] 2> 77901 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 77901 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 77903 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 77903 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 77905 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=14.7314453125, floorSegmentMB=0.9755859375, forceMergeDeletesPctAllowed=14.578976283521126, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 77906 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@25403d0d[forwardingCollection_shard1_replica_n1] main]
[junit4] 2> 77908 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 77919 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 77958 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 77958 INFO (searcherExecutor-283-thread-1-processing-n:127.0.0.1:58994_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:forwardingCollection r:core_node2) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 77959 INFO (searcherExecutor-283-thread-1-processing-n:127.0.0.1:58994_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:forwardingCollection r:core_node2) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 77959 INFO (searcherExecutor-283-thread-1-processing-n:127.0.0.1:58994_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:forwardingCollection r:core_node2) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 77959 INFO (searcherExecutor-283-thread-1-processing-n:127.0.0.1:58994_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:forwardingCollection r:core_node2) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 77960 INFO (searcherExecutor-283-thread-1-processing-n:127.0.0.1:58994_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:forwardingCollection r:core_node2) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 77960 INFO (searcherExecutor-283-thread-1-processing-n:127.0.0.1:58994_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:forwardingCollection r:core_node2) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 77961 INFO (searcherExecutor-283-thread-1-processing-n:127.0.0.1:58994_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:forwardingCollection r:core_node2) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 77961 INFO (searcherExecutor-283-thread-1-processing-n:127.0.0.1:58994_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:forwardingCollection r:core_node2) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 77961 INFO (searcherExecutor-283-thread-1-processing-n:127.0.0.1:58994_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:forwardingCollection r:core_node2) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 77963 INFO (searcherExecutor-283-thread-1-processing-n:127.0.0.1:58994_solr x:forwardingCollection_shard1_replica_n1 s:shard1 c:forwardingCollection r:core_node2) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.SolrCore [forwardingCollection_shard1_replica_n1] Registered new searcher Searcher@25403d0d[forwardingCollection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 77964 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1591787239861387264
[junit4] 2> 77983 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 77983 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 77983 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58994/solr/forwardingCollection_shard1_replica_n1/
[junit4] 2> 77983 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 77983 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:58994/solr/forwardingCollection_shard1_replica_n1/ has no replicas
[junit4] 2> 77983 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 77990 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:58994/solr/forwardingCollection_shard1_replica_n1/ shard1
[junit4] 2> 78094 INFO (zkCallback-146-thread-2-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/forwardingCollection/state.json] for collection [forwardingCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 78094 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/forwardingCollection/state.json] for collection [forwardingCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 78141 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 78145 INFO (qtp1365072223-709) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=forwardingCollection_shard1_replica_n1&action=CREATE&numShards=1&collection=forwardingCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1950
[junit4] 2> 78146 WARN (httpShardExecutor-274-thread-1-processing-n:127.0.0.1:58994_solr [http:////127.0.0.1:58994//solr] http:////127.0.0.1:58994//solr) [n:127.0.0.1:58994_solr ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=$&p=$&t=test&e=1518082606070&s=YGng73Cnn5gFKw8aifUHlELoRnA="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:46 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:46 GMT
[junit4] 2> 78165 INFO (qtp744172632-722) [n:127.0.0.1:55838_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 78245 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/forwardingCollection/state.json] for collection [forwardingCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 78245 INFO (zkCallback-146-thread-2-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/forwardingCollection/state.json] for collection [forwardingCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 79173 INFO (qtp744172632-722) [n:127.0.0.1:55838_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&replicationFactor=1&collection.configName=conf1&maxShardsPerNode=1&name=forwardingCollection&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=3377
[junit4] 2> 79175 WARN (TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=user&p=user&t=test&e=1518082605672&s=MBA+qk5pnLqiyd6RSGNabJkMFq4="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:45 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:45 GMT
[junit4] 2> 79175 INFO (TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: forwardingCollection failOnTimeout:true timeout (sec):100
[junit4] 2> 79176 INFO (TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: forwardingCollection
[junit4] 2> 79194 INFO (qtp744172632-722) [n:127.0.0.1:55838_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 79212 INFO (qtp1365072223-711) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.S.Request [forwardingCollection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 79213 WARN (qtp744172632-722) [n:127.0.0.1:55838_solr ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=$&p=$&t=test&e=1518082609085&s=oTfZal0L8hdsSSm3O9Tqd/BjxS8="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:49 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:49 GMT
[junit4] 2> 79214 WARN (TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=user&p=user&t=test&e=1518082609068&s=eCLfWUXhpGJmtqFuYIQYqREqu+Q="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:49 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:49 GMT
[junit4] 2> 79214 WARN (TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=$&p=$&t=test&e=1518082609085&s=oTfZal0L8hdsSSm3O9Tqd/BjxS8="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:49 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:49 GMT
[junit4] 2> 79218 INFO (qtp1365072223-711) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.S.Request [forwardingCollection_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 79219 WARN (TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=user&p=user&t=test&e=1518082609092&s=LWNLe5siE2w45ymmbSmw/WMXbho="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:49 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:49 GMT
[junit4] 2> 79219 INFO (TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testForwarding
[junit4] 2> 79271 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidProxyUser-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyInvalidProxyUser
[junit4] 2> 79276 WARN (TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidProxyUser-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=bar&p=bar&t=test&e=1518082609148&s=LHlu4KWAjtueYsyTNKfLgCVSdds="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:49 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:49 GMT
[junit4] 2> 79276 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidProxyUser-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyInvalidProxyUser
[junit4] 2> 79346 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigHosts-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNoConfigHosts
[junit4] 2> 79361 WARN (TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigHosts-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=noHosts&p=noHosts&t=test&e=1518082609222&s=aKFrOTy1MrfUODAXH+cH7ylZHCA="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:49 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:49 GMT
[junit4] 2> 79362 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigHosts-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNoConfigHosts
[junit4] 2> 79426 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyNullProxyUser-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNullProxyUser
[junit4] 2> 79428 INFO (qtp744172632-715) [ ] o.a.s.s.SolrDispatchFilter Error authenticating
[junit4] 2> java.lang.IllegalArgumentException: userName is NULL, empty or contains a '&'
[junit4] 2> at org.apache.hadoop.security.authentication.util.AuthToken.checkForIllegalArgument(AuthToken.java:87)
[junit4] 2> at org.apache.hadoop.security.authentication.util.AuthToken.<init>(AuthToken.java:70)
[junit4] 2> at org.apache.hadoop.security.authentication.server.AuthenticationToken.<init>(AuthenticationToken.java:58)
[junit4] 2> at org.apache.solr.security.HttpParamDelegationTokenPlugin$HttpParamDelegationTokenAuthenticationHandler$HttpParamAuthenticationHandler.authenticate(HttpParamDelegationTokenPlugin.java:213)
[junit4] 2> at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.authenticate(DelegationTokenAuthenticationHandler.java:348)
[junit4] 2> at org.apache.solr.security.RequestContinuesRecorderAuthenticationHandler.authenticate(RequestContinuesRecorderAuthenticationHandler.java:69)
[junit4] 2> at org.apache.hadoop.security.authentication.server.AuthenticationFilter.doFilter(AuthenticationFilter.java:518)
[junit4] 2> at org.apache.solr.security.DelegationTokenKerberosFilter.doFilter(DelegationTokenKerberosFilter.java:134)
[junit4] 2> at org.apache.solr.security.HttpParamDelegationTokenPlugin$HttpParamToRequestFilter.doFilter(HttpParamDelegationTokenPlugin.java:245)
[junit4] 2> at org.apache.solr.security.KerberosPlugin.doAuthenticate(KerberosPlugin.java:243)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:449)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:349)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:527)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:530)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 79432 WARN (qtp744172632-715) [ ] o.e.j.s.HttpChannel /solr/admin/collections
[junit4] 2> org.apache.solr.common.SolrException: Error during request authentication,
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:455)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:349)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:527)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:530)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.lang.IllegalArgumentException: userName is NULL, empty or contains a '&'
[junit4] 2> at org.apache.hadoop.security.authentication.util.AuthToken.checkForIllegalArgument(AuthToken.java:87)
[junit4] 2> at org.apache.hadoop.security.authentication.util.AuthToken.<init>(AuthToken.java:70)
[junit4] 2> at org.apache.hadoop.security.authentication.server.AuthenticationToken.<init>(AuthenticationToken.java:58)
[junit4] 2> at org.apache.solr.security.HttpParamDelegationTokenPlugin$HttpParamDelegationTokenAuthenticationHandler$HttpParamAuthenticationHandler.authenticate(HttpParamDelegationTokenPlugin.java:213)
[junit4] 2> at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.authenticate(DelegationTokenAuthenticationHandler.java:348)
[junit4] 2> at org.apache.solr.security.RequestContinuesRecorderAuthenticationHandler.authenticate(RequestContinuesRecorderAuthenticationHandler.java:69)
[junit4] 2> at org.apache.hadoop.security.authentication.server.AuthenticationFilter.doFilter(AuthenticationFilter.java:518)
[junit4] 2> at org.apache.solr.security.DelegationTokenKerberosFilter.doFilter(DelegationTokenKerberosFilter.java:134)
[junit4] 2> at org.apache.solr.security.HttpParamDelegationTokenPlugin$HttpParamToRequestFilter.doFilter(HttpParamDelegationTokenPlugin.java:245)
[junit4] 2> at org.apache.solr.security.KerberosPlugin.doAuthenticate(KerberosPlugin.java:243)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:449)
[junit4] 2> ... 31 more
[junit4] 2> 79463 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyNullProxyUser-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNullProxyUser
[junit4] 2> 79523 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateHost-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateHost
[junit4] 2> 79538 INFO (qtp744172632-722) [n:127.0.0.1:55838_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params doAs=bar&action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 79539 INFO (qtp744172632-722) [n:127.0.0.1:55838_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={doAs=bar&action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 79540 WARN (TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateHost-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=localHostAnyGroup&p=localHostAnyGroup&t=test&e=1518082609413&s=dNeXMsY9ZCwQRgHKBFNUzZBHUag="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:49 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:49 GMT
[junit4] 2> 79540 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateHost-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateHost
[junit4] 2> 79590 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidGroup-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyInvalidGroup
[junit4] 2> 79805 WARN (qtp744172632-715) [ ] o.a.h.s.ShellBasedUnixGroupsMapping got exception trying to get groups for user bar: id: invalid user name: "bar"
[junit4] 2> id: invalid user name: "bar"
[junit4] 2>
[junit4] 2> 79816 WARN (TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidGroup-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=bogusGroup&p=bogusGroup&t=test&e=1518082609466&s=2gncZPpa3nNthrzUtcaUysbiFsI="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:49 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:49 GMT
[junit4] 2> 79819 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidGroup-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyInvalidGroup
[junit4] 2> 79835 INFO (OverseerCollectionConfigSetProcessor-72159992018436101-127.0.0.1:58994_solr-n_0000000000) [n:127.0.0.1:58994_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 79861 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyUnknownRemote-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyUnknownRemote
[junit4] 2> 96436 WARN (jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127)) [n:127.0.0.1:55838_solr ] o.a.z.ClientCnxn Client session timed out, have not heard from server in 16903ms for sessionid 0x1005d2163410006
[junit4] 2> 96438 WARN (jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127)) [n:127.0.0.1:58994_solr ] o.a.z.ClientCnxn Client session timed out, have not heard from server in 16605ms for sessionid 0x1005d2163410005
[junit4] 2> 96438 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1005d2163410006, likely client has closed socket
[junit4] 2> 96439 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1005d2163410005, likely client has closed socket
[junit4] 2> 96440 WARN (TEST-TestSolrCloudWithSecureImpersonation.testProxyUnknownRemote-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=localHostAnyGroup&p=localHostAnyGroup&t=test&e=1518082609737&s=joS/Es6A/A4gUnNw8Is+nm91sdQ="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:36:49 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:36:49 GMT
[junit4] 2> 96441 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyUnknownRemote-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyUnknownRemote
[junit4] 2> 96461 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateGroup-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateGroup
[junit4] 2> 96533 INFO (qtp744172632-715) [n:127.0.0.1:55838_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params doAs=jenkins&action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 96539 INFO (jetty-launcher-130-thread-2-EventThread) [n:127.0.0.1:55838_solr ] o.a.c.f.s.ConnectionStateManager State change: SUSPENDED
[junit4] 2> 96540 WARN (zkConnectionManagerCallback-161-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66a23363 name: ZooKeeperConnection Watcher:127.0.0.1:43127/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None
[junit4] 2> 96540 WARN (zkConnectionManagerCallback-161-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has disconnected
[junit4] 2> 96543 WARN (zkConnectionManagerCallback-147-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@65f22bae name: ZooKeeperConnection Watcher:127.0.0.1:43127/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None
[junit4] 2> 96543 WARN (zkConnectionManagerCallback-147-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ConnectionManager zkClient has disconnected
[junit4] 2> 96546 WARN (zkConnectionManagerCallback-149-thread-1-processing-n:127.0.0.1:55838_solr) [n:127.0.0.1:55838_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@65ac492a name: ZooKeeperConnection Watcher:127.0.0.1:43127/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None
[junit4] 2> 96546 WARN (zkConnectionManagerCallback-149-thread-1-processing-n:127.0.0.1:55838_solr) [n:127.0.0.1:55838_solr ] o.a.s.c.c.ConnectionManager zkClient has disconnected
[junit4] 2> 97552 WARN (jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127)) [n:127.0.0.1:55838_solr ] o.a.z.ClientCnxn Unable to reconnect to ZooKeeper service, session 0x1005d2163410008 has expired
[junit4] 2> 97581 WARN (TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[F4F19B2AE1EB4A26]-SendThread(127.0.0.1:43127)) [ ] o.a.z.ClientCnxn Unable to reconnect to ZooKeeper service, session 0x1005d216341000b has expired
[junit4] 2> 97581 WARN (zkConnectionManagerCallback-161-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66a23363 name: ZooKeeperConnection Watcher:127.0.0.1:43127/solr got event WatchedEvent state:Expired type:None path:null path: null type: None
[junit4] 2> 97581 WARN (zkConnectionManagerCallback-161-thread-1) [ ] o.a.s.c.c.ConnectionManager Our previous ZooKeeper session was expired. Attempting to reconnect to recover relationship with ZooKeeper...
[junit4] 2> 97586 WARN (zkConnectionManagerCallback-161-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Connection expired - starting a new one...
[junit4] 2> 97589 INFO (zkConnectionManagerCallback-161-thread-1-EventThread) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 97590 INFO (zkConnectionManagerCallback-161-thread-1) [ ] o.a.s.c.c.ConnectionManager Connection with ZooKeeper reestablished.
[junit4] 2> 97592 INFO (zkConnectionManagerCallback-161-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Reconnected to ZooKeeper
[junit4] 2> 97592 INFO (zkConnectionManagerCallback-161-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient Connected:true
[junit4] 2> 98216 INFO (zkConnectionManagerCallback-149-thread-1-processing-n:127.0.0.1:55838_solr) [n:127.0.0.1:55838_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 98217 INFO (qtp744172632-715) [n:127.0.0.1:55838_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={doAs=jenkins&action=LIST&wt=javabin&version=2} status=0 QTime=1684
[junit4] 2> 98217 WARN (TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateGroup-seed#[F4F19B2AE1EB4A26]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=anyHostUsersGroup&p=anyHostUsersGroup&t=test&e=1518082626337&s=GO8/SwD5sT/bkSab4OHCqo4OM2A="; Path=/; Domain=127.0.0.1; Expires= , 08- -2018 09:37:06 GMT; HttpOnly". Invalid 'expires' attribute: , 08- -2018 09:37:06 GMT
[junit4] 2> 98218 INFO (TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateGroup-seed#[F4F19B2AE1EB4A26]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateGroup
[junit4] 2> 98223 INFO (jetty-closer-131-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3dac27c9{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 98223 INFO (jetty-closer-131-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1859939846
[junit4] 2> 98223 INFO (jetty-closer-131-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 98223 INFO (jetty-closer-131-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@db3d8e9: rootName = solr_55838, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4ca6ee9b
[junit4] 2> 98223 INFO (jetty-closer-131-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7dca1cbe{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 98223 INFO (jetty-closer-131-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1376186873
[junit4] 2> 98224 INFO (jetty-closer-131-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 98224 INFO (jetty-closer-131-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@78eca089: rootName = solr_58994, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@312f002e
[junit4] 2> 98228 INFO (jetty-closer-131-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 98228 INFO (jetty-closer-131-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1ed7bbcf: rootName = solr_55838, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1fa9ffe0
[junit4] 2> 98229 INFO (jetty-closer-131-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 98229 INFO (jetty-closer-131-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b0186ec: rootName = solr_58994, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1fa9ffe0
[junit4] 2> 98230 INFO (jetty-closer-131-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 98230 INFO (jetty-closer-131-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5891495c: rootName = solr_55838, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3299714f
[junit4] 2> 98230 INFO (jetty-closer-131-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 98230 INFO (jetty-closer-131-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4367e7d9: rootName = solr_58994, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3299714f
[junit4] 2> 98236 INFO (jetty-closer-131-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:55838_solr
[junit4] 2> 98237 INFO (jetty-closer-131-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 98460 INFO (zkConnectionManagerCallback-147-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 98461 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 98462 INFO (jetty-closer-131-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:58994_solr
[junit4] 2> 98463 INFO (jetty-closer-131-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 98465 INFO (coreCloseExecutor-289-thread-1) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.c.SolrCore [forwardingCollection_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@58cde050
[junit4] 2> 98465 INFO (coreCloseExecutor-289-thread-1) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.forwardingCollection.shard1.replica_n1, tag=1489887312
[junit4] 2> 98465 INFO (zkCallback-146-thread-1-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 98465 INFO (coreCloseExecutor-289-thread-1) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@22b27c41: rootName = solr_58994, domain = solr.core.forwardingCollection.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.forwardingCollection.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@31b67653
[junit4] 2> 98465 INFO (zkCallback-146-thread-6-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/forwardingCollection/state.json] for collection [forwardingCollection] has occurred - updating... (live nodes size: [0])
[junit4] 2> 98465 INFO (zkCallback-146-thread-7-processing-n:127.0.0.1:58994_solr) [n:127.0.0.1:58994_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/forwardingCollection/state.json] for collection [forwardingCollection] has occurred - updating... (live nodes size: [0])
[junit4] 2> 98475 INFO (coreCloseExecutor-289-thread-1) [n:127.0.0.1:58994_solr c:forwardingCollection s:shard1 r:core_node2 x:forwardingCollection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.forwardingCollection.shard1.leader, tag=1489887312
[junit4] 2> 98482 INFO (jetty-closer-131-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72159992018436101-127.0.0.1:58994_solr-n_0000000000) closing
[junit4] 2> 98482 INFO (OverseerStateUpdate-72159992018436101-127.0.0.1:58994_solr-n_0000000000) [n:127.0.0.1:58994_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:58994_solr
[junit4] 2> 98483 WARN (OverseerAutoScalingTriggerThread-72159992018436101-127.0.0.1:58994_solr-n_0000000000) [n:127.0.0.1:58994_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 98491 ERROR (Thread[Thread-202,5,TGRP-TestSolrCloudWithSecureImpersonation]) [n:127.0.0.1:58994_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 98498 WARN (jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127)) [n:127.0.0.1:58994_solr ] o.a.z.ClientCnxn Unable to reconnect to ZooKeeper service, session 0x1005d2163410007 has expired
[junit4] 2> 98508 INFO (jetty-closer-131-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3d5cf081{/solr,null,UNAVAILABLE}
[junit4] 2> 98508 INFO (jetty-closer-131-thread-2) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 99740 WARN (zkCallback-148-thread-2-processing-n:127.0.0.1:55838_solr) [n:127.0.0.1:55838_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 99742 ERROR (Thread[Thread-204,5,TGRP-TestSolrCloudWithSecureImpersonation]) [n:127.0.0.1:55838_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 99747 INFO (jetty-closer-131-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@78b303ea{/solr,null,UNAVAILABLE}
[junit4] 2> 99747 INFO (jetty-closer-131-thread-1) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 99750 ERROR (SUITE-TestSolrCloudWithSecureImpersonation-seed#[F4F19B2AE1EB4A26]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 99750 INFO (SUITE-TestSolrCloudWithSecureImpersonation-seed#[F4F19B2AE1EB4A26]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43127 43127
[junit4] 2> 109794 INFO (Thread-185) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43127 43127
[junit4] 2> 109795 WARN (Thread-185) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/forwardingCollection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/collections/forwardingCollection/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_3
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_2
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/live_nodes
[junit4] 2> 4 /solr/collections
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
[junit4] 2>
[junit4] 2> 109876 INFO (SUITE-TestSolrCloudWithSecureImpersonation-seed#[F4F19B2AE1EB4A26]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Feb 07, 2018 11:37:19 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> 126602 ERROR (jetty-launcher-130-thread-2-EventThread) [n:127.0.0.1:55838_solr ] o.a.c.f.i.CuratorFrameworkImpl Watcher exception
[junit4] 2> java.lang.IllegalStateException: Client is not started
[junit4] 2> at com.google.common.base.Preconditions.checkState(Preconditions.java:149)
[junit4] 2> at org.apache.curator.CuratorZookeeperClient.getZooKeeper(CuratorZookeeperClient.java:113)
[junit4] 2> at org.apache.curator.framework.imps.CuratorFrameworkImpl.getZooKeeper(CuratorFrameworkImpl.java:477)
[junit4] 2> at org.apache.curator.framework.imps.NamespaceFacade.getZooKeeper(NamespaceFacade.java:112)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl$4.call(GetDataBuilderImpl.java:302)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl$4.call(GetDataBuilderImpl.java:291)
[junit4] 2> at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:107)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] 2> at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:531)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506)
[junit4] 2> 126604 WARN (jetty-launcher-130-thread-2-EventThread) [n:127.0.0.1:55838_solr ] o.a.c.ConnectionState Session expired event received
[junit4] 2> 126605 WARN (jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127)) [n:127.0.0.1:55838_solr ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1144)
[junit4] 2> 127713 WARN (jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127)) [n:127.0.0.1:55838_solr ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1144)
[junit4] 2> 128814 WARN (jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127)) [n:127.0.0.1:55838_solr ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1144)
[junit4] 2> 129665 ERROR (jetty-launcher-130-thread-1-EventThread) [n:127.0.0.1:58994_solr ] o.a.c.f.i.CuratorFrameworkImpl Watcher exception
[junit4] 2> java.lang.IllegalStateException: Client is not started
[junit4] 2> at com.google.common.base.Preconditions.checkState(Preconditions.java:149)
[junit4] 2> at org.apache.curator.CuratorZookeeperClient.getZooKeeper(CuratorZookeeperClient.java:113)
[junit4] 2> at org.apache.curator.framework.imps.CuratorFrameworkImpl.getZooKeeper(CuratorFrameworkImpl.java:477)
[junit4] 2> at org.apache.curator.framework.imps.NamespaceFacade.getZooKeeper(NamespaceFacade.java:112)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl$4.call(GetDataBuilderImpl.java:302)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl$4.call(GetDataBuilderImpl.java:291)
[junit4] 2> at org.apache.curator.RetryLoop.callWithRetry(RetryLoop.java:107)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.pathInForeground(GetDataBuilderImpl.java:288)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:279)
[junit4] 2> at org.apache.curator.framework.imps.GetDataBuilderImpl.forPath(GetDataBuilderImpl.java:41)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.readValue(SharedValue.java:244)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue.access$100(SharedValue.java:44)
[junit4] 2> at org.apache.curator.framework.recipes.shared.SharedValue$1.process(SharedValue.java:61)
[junit4] 2> at org.apache.curator.framework.imps.NamespaceWatcher.process(NamespaceWatcher.java:67)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:531)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:506)
[junit4] 2> 129666 WARN (jetty-launcher-130-thread-1-EventThread) [n:127.0.0.1:58994_solr ] o.a.c.ConnectionState Session expired event received
[junit4] 2> 129668 WARN (jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127)) [n:127.0.0.1:58994_solr ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1144)
[junit4] 2> 129925 WARN (jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127)) [n:127.0.0.1:55838_solr ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1144)
[junit4] 2> Feb 07, 2018 11:37:39 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 4 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:
[junit4] 2> 1) Thread[id=840, name=jetty-launcher-130-thread-1-EventThread, state=WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4] 2> 2) Thread[id=837, name=jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
[junit4] 2> 3) Thread[id=838, name=jetty-launcher-130-thread-2-EventThread, state=WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4] 2> 4) Thread[id=839, name=jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
[junit4] 2> Feb 07, 2018 11:37:39 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=840, name=jetty-launcher-130-thread-1-EventThread, state=WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] 2> 2) Thread[id=837, name=jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127), state=TIME
[...truncated too long message...]
30-thread-1-SendThread(127.0.0.1:43127)) [n:127.0.0.1:58994_solr ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1144)
[junit4] 2> 132163 WARN (jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127)) [n:127.0.0.1:55838_solr ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1144)
[junit4] 2> 132676 WARN (jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127)) [n:127.0.0.1:58994_solr ] o.a.z.c.StaticHostProvider Unexpected exception
[junit4] 2> java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
[junit4] 2> 132677 WARN (jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127)) [n:127.0.0.1:58994_solr ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1144)
[junit4] 2> 132676 WARN (jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127)) [n:127.0.0.1:55838_solr ] o.a.z.c.StaticHostProvider Unexpected exception
[junit4] 2> java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
[junit4] 2> 132677 WARN (jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127)) [n:127.0.0.1:55838_solr ] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1144)
[junit4] 2> Feb 07, 2018 11:37:43 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=837, name=jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
[junit4] 2> 2) Thread[id=839, name=jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1034, maxMBSortInHeap=5.072771720449946, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@73a6e857), locale=ar-DZ, timezone=America/Denver
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=3,threads=3,free=103918288,total=264241152
[junit4] 2> NOTE: All tests run in this JVM: [TestRTGBase, PrimUtilsTest, ShardRoutingTest, TestSimpleTrackingShardHandler, TestSolrCloudWithSecureImpersonation]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCloudWithSecureImpersonation -Dtests.seed=F4F19B2AE1EB4A26 -Dtests.slow=true -Dtests.locale=ar-DZ -Dtests.timezone=America/Denver -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestSolrCloudWithSecureImpersonation (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:
[junit4] > 1) Thread[id=840, name=jetty-launcher-130-thread-1-EventThread, state=WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4] > 2) Thread[id=837, name=jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
[junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
[junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
[junit4] > 3) Thread[id=838, name=jetty-launcher-130-thread-2-EventThread, state=WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4] > 4) Thread[id=839, name=jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
[junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
[junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
[junit4] > at __randomizedtesting.SeedInfo.seed([F4F19B2AE1EB4A26]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=837, name=jetty-launcher-130-thread-2-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
[junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
[junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
[junit4] > 2) Thread[id=839, name=jetty-launcher-130-thread-1-SendThread(127.0.0.1:43127), state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:105)
[junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:1000)
[junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1063)
[junit4] > at __randomizedtesting.SeedInfo.seed([F4F19B2AE1EB4A26]:0)
[junit4] Completed [14/775 (1!)] on J1 in 68.47s, 12 tests, 2 errors <<< FAILURES!
[...truncated 50600 lines...]
[JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build #
1665 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1665/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.request.SchemaTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.client.solrj.request.SchemaTest: 1) Thread[id=43, name=qtp1340764449-43, state=TIMED_WAITING, group=TGRP-SchemaTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.client.solrj.request.SchemaTest:
1) Thread[id=43, name=qtp1340764449-43, state=TIMED_WAITING, group=TGRP-SchemaTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([E127F7CB8692A6F4]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.request.SchemaTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=43, name=qtp1340764449-43, state=TIMED_WAITING, group=TGRP-SchemaTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=43, name=qtp1340764449-43, state=TIMED_WAITING, group=TGRP-SchemaTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([E127F7CB8692A6F4]:0)
Build Log:
[...truncated 15139 lines...]
[junit4] Suite: org.apache.solr.client.solrj.request.SchemaTest
[junit4] 2> 0 INFO (SUITE-SchemaTest-seed#[E127F7CB8692A6F4]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/init-core-data-001
[junit4] 2> 121 INFO (SUITE-SchemaTest-seed#[E127F7CB8692A6F4]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 178 INFO (SUITE-SchemaTest-seed#[E127F7CB8692A6F4]-worker) [ ] o.e.j.u.log Logging initialized @2631ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 204 INFO (SUITE-SchemaTest-seed#[E127F7CB8692A6F4]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 215 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting addFieldTypeWithAnalyzerClassAccuracy
[junit4] 2> 286 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 286 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 287 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-003/cores/core
[junit4] 2> 489 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 511 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 511 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 516 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 546 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5c8e984e{/solr,null,AVAILABLE}
[junit4] 2> 587 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5d5a9645{HTTP/1.1,[http/1.1]}{127.0.0.1:57766}
[junit4] 2> 587 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server Started @3044ms
[junit4] 2> 587 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57766, configSetBaseDir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-001, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-003/cores}
[junit4] 2> 646 ERROR (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 647 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 647 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 647 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 668 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-08T11:31:50.987Z
[junit4] 2> 704 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.SolrResourceLoader Using system property solr.solr.home: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-001
[junit4] 2> 710 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-001/solr.xml
[junit4] 2> 1622 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-003/cores
[junit4] 2> 1627 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1642 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1698 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1740 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 1829 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2271 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 2277 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 2298 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 2298 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 2315 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2354 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-001/collection1/conf/managed-schema
[junit4] 2> 2359 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-001/collection1/conf/schema.xml to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-001/collection1/conf/schema.xml.bak
[junit4] 2> 2360 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-001/collection1, trusted=true
[junit4] 2> 2447 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2454 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-001/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-003/cores/core/data/]
[junit4] 2> 3304 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 3314 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3314 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3368 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3e01197d[collection1] main]
[junit4] 2> 3396 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-001/collection1/conf
[junit4] 2> 3441 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3661 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3e01197d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4170 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#57766
[junit4] 2> 4578 INFO (qtp734242553-19) [ x:collection1] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-001/collection1/conf/managed-schema
[junit4] 2> 4580 INFO (qtp734242553-19) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=108
[junit4] 2> 4637 INFO (qtp734242553-20) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema/fieldtypes/nameText params={wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 4653 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5d5a9645{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 4654 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1013470983
[junit4] 2> 4656 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 4656 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 4657 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 4658 INFO (coreCloseExecutor-11-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@14073757
[junit4] 2> 4658 INFO (coreCloseExecutor-11-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=336017239
[junit4] 2> 4672 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5c8e984e{/solr,null,UNAVAILABLE}
[junit4] 2> 4672 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 4687 INFO (TEST-SchemaTest.addFieldTypeWithAnalyzerClassAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending addFieldTypeWithAnalyzerClassAccuracy
[junit4] 2> 4690 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGetDynamicFieldsAccuracy
[junit4] 2> 4834 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 4834 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 4835 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-006/cores/core
[junit4] 2> 4837 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 4848 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4848 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4848 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 4849 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6dc22b95{/solr,null,AVAILABLE}
[junit4] 2> 4851 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@71c22f1e{HTTP/1.1,[http/1.1]}{127.0.0.1:35471}
[junit4] 2> 4851 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server Started @7310ms
[junit4] 2> 4852 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35471, configSetBaseDir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-004, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-006/cores}
[junit4] 2> 4853 ERROR (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4853 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 4853 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 4853 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4853 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-08T11:31:55.191Z
[junit4] 2> 4854 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-004/solr.xml
[junit4] 2> 4915 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-006/cores
[junit4] 2> 4915 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 4925 INFO (coreLoadExecutor-18-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 4945 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4959 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 4979 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 5112 WARN (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 5112 WARN (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 5113 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 5115 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-004/collection1/conf/managed-schema
[junit4] 2> 5117 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-004/collection1/conf/schema.xml to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-004/collection1/conf/schema.xml.bak
[junit4] 2> 5117 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-004/collection1, trusted=true
[junit4] 2> 5117 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 5117 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-004/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-006/cores/core/data/]
[junit4] 2> 5175 WARN (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 5176 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 5176 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 5178 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@43fe405e[collection1] main]
[junit4] 2> 5178 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-004/collection1/conf
[junit4] 2> 5179 INFO (coreLoadExecutor-18-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 5186 INFO (searcherExecutor-19-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@43fe405e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 5685 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#35471
[junit4] 2> 5699 INFO (qtp1340764449-38) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema/dynamicfields params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 5709 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@71c22f1e{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 5709 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1837275894
[junit4] 2> 5710 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 5710 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 5710 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 5711 INFO (coreCloseExecutor-23-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@50f6c52
[junit4] 2> 5712 INFO (coreCloseExecutor-23-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=84896850
[junit4] 2> 5727 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6dc22b95{/solr,null,UNAVAILABLE}
[junit4] 2> 5727 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 35727 WARN (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.u.t.QueuedThreadPool QueuedThreadPool@qtp1340764449{STOPPING,8<=8<=10000,i=0,q=1} Couldn't stop Thread[qtp1340764449-43,5,TGRP-SchemaTest]
[junit4] 2> 35728 INFO (TEST-SchemaTest.testGetDynamicFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testGetDynamicFieldsAccuracy
[junit4] 2> 35732 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAddFieldAccuracy
[junit4] 2> 35761 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 35761 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 35762 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-009/cores/core
[junit4] 2> 35764 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 35764 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 35764 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 35764 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 35765 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5d354f9c{/solr,null,AVAILABLE}
[junit4] 2> 35765 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@31583680{HTTP/1.1,[http/1.1]}{127.0.0.1:32961}
[junit4] 2> 35765 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server Started @38234ms
[junit4] 2> 35765 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=32961, configSetBaseDir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-007, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-009/cores}
[junit4] 2> 35766 ERROR (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 35766 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 35766 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 35766 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 35766 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-08T11:32:26.104Z
[junit4] 2> 35766 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-007/solr.xml
[junit4] 2> 35807 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-009/cores
[junit4] 2> 35811 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 35813 INFO (coreLoadExecutor-30-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 35828 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 35838 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 35848 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 35922 WARN (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 35923 WARN (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 35924 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 35926 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-007/collection1/conf/managed-schema
[junit4] 2> 35927 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-007/collection1/conf/schema.xml to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-007/collection1/conf/schema.xml.bak
[junit4] 2> 35927 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-007/collection1, trusted=true
[junit4] 2> 35927 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 35927 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-007/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-009/cores/core/data/]
[junit4] 2> 35979 WARN (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 35980 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 35980 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 35983 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c095a2b[collection1] main]
[junit4] 2> 35983 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-007/collection1/conf
[junit4] 2> 35983 INFO (coreLoadExecutor-30-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 35985 INFO (searcherExecutor-31-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3c095a2b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 36485 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#32961
[junit4] 2> 36493 INFO (qtp1952629226-59) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema/fields params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 36500 INFO (qtp1952629226-60) [ x:collection1] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-007/collection1/conf/managed-schema
[junit4] 2> 36501 INFO (qtp1952629226-60) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 36503 INFO (qtp1952629226-62) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema/fields params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 36509 INFO (qtp1952629226-60) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema/fields/accuracyField params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 36513 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@31583680{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 36513 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=326856757
[junit4] 2> 36513 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 36513 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 36513 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 36514 INFO (coreCloseExecutor-35-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@38db6078
[junit4] 2> 36514 INFO (coreCloseExecutor-35-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=953901176
[junit4] 2> 36524 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5d354f9c{/solr,null,UNAVAILABLE}
[junit4] 2> 36524 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 36526 INFO (TEST-SchemaTest.testAddFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAddFieldAccuracy
[junit4] 2> 36532 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReplaceDynamicFieldAccuracy
[junit4] 2> 36556 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 36556 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 36558 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-012/cores/core
[junit4] 2> 36560 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 36561 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 36561 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 36561 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 36561 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5710de1e{/solr,null,AVAILABLE}
[junit4] 2> 36562 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6cb592e4{HTTP/1.1,[http/1.1]}{127.0.0.1:55690}
[junit4] 2> 36562 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server Started @39031ms
[junit4] 2> 36562 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55690, configSetBaseDir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-010, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-012/cores}
[junit4] 2> 36563 ERROR (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 36563 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 36563 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 36564 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 36564 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-08T11:32:26.902Z
[junit4] 2> 36564 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-010/solr.xml
[junit4] 2> 36607 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-012/cores
[junit4] 2> 36607 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 36608 INFO (coreLoadExecutor-42-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 36625 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 36635 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 36643 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 36717 WARN (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 36717 WARN (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 36717 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 36718 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-010/collection1/conf/managed-schema
[junit4] 2> 36719 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-010/collection1/conf/schema.xml to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-010/collection1/conf/schema.xml.bak
[junit4] 2> 36720 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-010/collection1, trusted=true
[junit4] 2> 36720 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 36720 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-010/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-012/cores/core/data/]
[junit4] 2> 36779 WARN (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 36780 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 36780 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 36782 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2158b21c[collection1] main]
[junit4] 2> 36783 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-010/collection1/conf
[junit4] 2> 36783 INFO (coreLoadExecutor-42-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 36784 INFO (searcherExecutor-43-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2158b21c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 37285 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#55690
[junit4] 2> 37311 INFO (qtp766441049-81) [ x:collection1] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-010/collection1/conf/managed-schema
[junit4] 2> 37312 INFO (qtp766441049-81) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=16
[junit4] 2> 37321 INFO (qtp766441049-84) [ x:collection1] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-010/collection1/conf/managed-schema
[junit4] 2> 37322 INFO (qtp766441049-84) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 37326 INFO (qtp766441049-86) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema/dynamicfields/*_replace params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 37329 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6cb592e4{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 37330 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=193777933
[junit4] 2> 37330 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 37330 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 37331 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 37331 INFO (coreCloseExecutor-47-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3e73d017
[junit4] 2> 37331 INFO (coreCloseExecutor-47-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1047777303
[junit4] 2> 37338 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5710de1e{/solr,null,UNAVAILABLE}
[junit4] 2> 37338 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 37341 INFO (TEST-SchemaTest.testReplaceDynamicFieldAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReplaceDynamicFieldAccuracy
[junit4] 2> 37346 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGetFieldsAccuracy
[junit4] 2> 37377 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 37377 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 37380 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-015/cores/core
[junit4] 2> 37381 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 37382 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 37382 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 37382 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 37382 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@260f7c28{/solr,null,AVAILABLE}
[junit4] 2> 37383 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4a7e4a39{HTTP/1.1,[http/1.1]}{127.0.0.1:49304}
[junit4] 2> 37383 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server Started @39852ms
[junit4] 2> 37383 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49304, configSetBaseDir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-013, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-015/cores}
[junit4] 2> 37383 ERROR (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 37384 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 37384 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 37384 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 37384 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-08T11:32:27.722Z
[junit4] 2> 37384 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-013/solr.xml
[junit4] 2> 37425 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-015/cores
[junit4] 2> 37425 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 37428 INFO (coreLoadExecutor-54-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 37438 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 37449 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 37456 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 37517 WARN (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 37517 WARN (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 37518 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 37519 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-013/collection1/conf/managed-schema
[junit4] 2> 37520 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-013/collection1/conf/schema.xml to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-013/collection1/conf/schema.xml.bak
[junit4] 2> 37520 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-013/collection1, trusted=true
[junit4] 2> 37520 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 37520 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-013/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-015/cores/core/data/]
[junit4] 2> 37619 WARN (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 37625 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 37628 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 37631 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c158009[collection1] main]
[junit4] 2> 37632 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-013/collection1/conf
[junit4] 2> 37632 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 37633 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#49304
[junit4] 2> 37633 INFO (searcherExecutor-55-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c158009[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 37639 INFO (qtp1859575922-103) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema/fields params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 37641 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4a7e4a39{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 37642 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=417837434
[junit4] 2> 37642 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 37642 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 37642 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 37643 INFO (coreCloseExecutor-59-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@221449d5
[junit4] 2> 37643 INFO (coreCloseExecutor-59-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=571754965
[junit4] 2> 37647 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@260f7c28{/solr,null,UNAVAILABLE}
[junit4] 2> 37647 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 37648 INFO (TEST-SchemaTest.testGetFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testGetFieldsAccuracy
[junit4] 2> 37653 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGetFieldTypeAccuracy
[junit4] 2> 37683 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 37683 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 37688 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-018/cores/core
[junit4] 2> 37690 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 37691 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 37691 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 37691 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 37692 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3d2ba0fd{/solr,null,AVAILABLE}
[junit4] 2> 37692 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5d0e8c58{HTTP/1.1,[http/1.1]}{127.0.0.1:61262}
[junit4] 2> 37692 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server Started @40161ms
[junit4] 2> 37693 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61262, configSetBaseDir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-016, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-018/cores}
[junit4] 2> 37694 ERROR (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 37694 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 37694 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 37694 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 37694 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-08T11:32:28.032Z
[junit4] 2> 37694 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-016/solr.xml
[junit4] 2> 37734 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-018/cores
[junit4] 2> 37734 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 37736 INFO (coreLoadExecutor-66-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 37745 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 37754 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 37760 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 37833 WARN (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 37833 WARN (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 37834 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 37836 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-016/collection1/conf/managed-schema
[junit4] 2> 37837 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-016/collection1/conf/schema.xml to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-016/collection1/conf/schema.xml.bak
[junit4] 2> 37838 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-016/collection1, trusted=true
[junit4] 2> 37838 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 37838 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-016/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-018/cores/core/data/]
[junit4] 2> 37885 WARN (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 37886 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 37886 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 37888 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4f15cc38[collection1] main]
[junit4] 2> 37888 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-016/collection1/conf
[junit4] 2> 37888 INFO (coreLoadExecutor-66-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 37889 INFO (searcherExecutor-67-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4f15cc38[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 37889 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#61262
[junit4] 2> 37896 INFO (qtp346290438-125) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema/fieldtypes/string params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 37899 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5d0e8c58{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 37899 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=410487693
[junit4] 2> 37899 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 37899 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 37899 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 37900 INFO (coreCloseExecutor-71-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@325d91d6
[junit4] 2> 37900 INFO (coreCloseExecutor-71-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=844992982
[junit4] 2> 37909 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3d2ba0fd{/solr,null,UNAVAILABLE}
[junit4] 2> 37909 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 37912 INFO (TEST-SchemaTest.testGetFieldTypeAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testGetFieldTypeAccuracy
[junit4] 2> 37916 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail
[junit4] 2> 37944 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 37944 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 37945 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-021/cores/core
[junit4] 2> 37946 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 37947 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 37947 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 37947 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 37947 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@40706056{/solr,null,AVAILABLE}
[junit4] 2> 37948 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6197dbe7{HTTP/1.1,[http/1.1]}{127.0.0.1:42460}
[junit4] 2> 37948 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.Server Started @40418ms
[junit4] 2> 37949 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42460, configSetBaseDir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-019, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-021/cores}
[junit4] 2> 37949 ERROR (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 37950 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 37950 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 37950 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 37950 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-08T11:32:28.288Z
[junit4] 2> 37951 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-019/solr.xml
[junit4] 2> 37989 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-021/cores
[junit4] 2> 37989 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 37991 INFO (coreLoadExecutor-78-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 38009 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 38018 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 38025 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 38100 WARN (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 38100 WARN (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 38103 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 38104 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-019/collection1/conf/managed-schema
[junit4] 2> 38106 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-019/collection1/conf/schema.xml to /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-019/collection1/conf/schema.xml.bak
[junit4] 2> 38106 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-019/collection1, trusted=true
[junit4] 2> 38106 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 38106 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-019/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-021/cores/core/data/]
[junit4] 2> 38160 WARN (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 38160 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 38160 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 38163 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@39ccbf39[collection1] main]
[junit4] 2> 38163 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.request.SchemaTest_E127F7CB8692A6F4-001/tempDir-019/collection1/conf
[junit4] 2> 38164 INFO (coreLoadExecutor-78-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 38165 INFO (searcherExecutor-79-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@39ccbf39[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 38665 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#42460
[junit4] 2> 38678 ERROR (qtp111584771-145) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: error processing commands, errors: [{delete-dynamic-field={name=*_notexists}, errorMessages=[The dynamic field '*_notexists' is not present in this schema, and so cannot be deleted.
[junit4] 2> ]}],
[junit4] 2> at org.apache.solr.handler.SchemaHandler.handleRequestBody(SchemaHandler.java:92)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:195)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:517)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:380)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:530)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 38679 INFO (qtp111584771-145) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema params={wt=javabin&version=2} status=400 QTime=3
[junit4] 2> 38684 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6197dbe7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 38685 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=251960497
[junit4] 2> 38685 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 38685 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 38685 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 38687 INFO (coreCloseExecutor-83-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@b769533
[junit4] 2> 38687 INFO (coreCloseExecutor-83-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=192320819
[junit4] 2> 38697 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@40706056{/solr,null,UNAVAILABLE}
[junit4] 2> 38697 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 38699 INFO (TEST-SchemaTest.deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending deletingADynamicFieldThatDoesntExistInTheSchemaShouldFail
[junit4] 2> 38711 INFO (TEST-SchemaTest.testGetCopyFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGetCopyFieldsAccuracy
[junit4] 2> 38747 INFO (TEST-SchemaTest.testGetCopyFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 38748 INFO (TEST-SchemaTest.testGetCopyFieldsAccuracy-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ####ini
[...truncated too long message...]
(SessionHandler.java:1564)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:530)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 52247 INFO (qtp1526401901-758) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/schema params={wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 52249 INFO (TEST-SchemaTest.deletingAFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@688df5d0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 52249 INFO (TEST-SchemaTest.deletingAFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1918789557
[junit4] 2> 52249 INFO (TEST-SchemaTest.deletingAFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 52249 INFO (TEST-SchemaTest.deletingAFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 52249 INFO (TEST-SchemaTest.deletingAFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 52250 INFO (coreCloseExecutor-419-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7778310c
[junit4] 2> 52250 INFO (coreCloseExecutor-419-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2004365580
[junit4] 2> 52255 INFO (TEST-SchemaTest.deletingAFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@42bfa1c2{/solr,null,UNAVAILABLE}
[junit4] 2> 52255 INFO (TEST-SchemaTest.deletingAFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 52257 INFO (TEST-SchemaTest.deletingAFieldThatDoesntExistInTheSchemaShouldFail-seed#[E127F7CB8692A6F4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending deletingAFieldThatDoesntExistInTheSchemaShouldFail
[junit4] 2> 52259 INFO (SUITE-SchemaTest-seed#[E127F7CB8692A6F4]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Feb 08, 2018 11:32:42 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> Feb 08, 2018 11:33:03 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.client.solrj.request.SchemaTest:
[junit4] 2> 1) Thread[id=43, name=qtp1340764449-43, state=TIMED_WAITING, group=TGRP-SchemaTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Feb 08, 2018 11:33:03 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=43, name=qtp1340764449-43, state=TIMED_WAITING, group=TGRP-SchemaTest]
[junit4] 2> Feb 08, 2018 11:33:06 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=43, name=qtp1340764449-43, state=TIMED_WAITING, group=TGRP-SchemaTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@56097887), locale=ga-IE, timezone=America/La_Paz
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=3,threads=2,free=16618712,total=97386496
[junit4] 2> NOTE: All tests run in this JVM: [CeilingEvaluatorTest, NamedListTest, SchemaTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SchemaTest -Dtests.seed=E127F7CB8692A6F4 -Dtests.slow=true -Dtests.locale=ga-IE -Dtests.timezone=America/La_Paz -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | SchemaTest (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.client.solrj.request.SchemaTest:
[junit4] > 1) Thread[id=43, name=qtp1340764449-43, state=TIMED_WAITING, group=TGRP-SchemaTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([E127F7CB8692A6F4]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=43, name=qtp1340764449-43, state=TIMED_WAITING, group=TGRP-SchemaTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([E127F7CB8692A6F4]:0)
[junit4] Completed [32/146 (1!)] on J0 in 75.73s, 35 tests, 2 errors <<< FAILURES!
[...truncated 47609 lines...]