Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1453/
2 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.security.BasicAuthIntegrationTest
Error Message:
2 threads leaked from SUITE scope at
org.apache.solr.security.BasicAuthIntegrationTest: 1) Thread[id=23404,
name=qtp1899892224-23404, state=TIMED_WAITING,
group=TGRP-BasicAuthIntegrationTest] 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) 2) Thread[id=23406,
name=qtp1899892224-23406, state=TIMED_WAITING,
group=TGRP-BasicAuthIntegrationTest] 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: 2 threads leaked from SUITE
scope at org.apache.solr.security.BasicAuthIntegrationTest:
1) Thread[id=23404, name=qtp1899892224-23404, state=TIMED_WAITING,
group=TGRP-BasicAuthIntegrationTest]
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)
2) Thread[id=23406, name=qtp1899892224-23406, state=TIMED_WAITING,
group=TGRP-BasicAuthIntegrationTest]
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([4052A8428686ABF4]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.security.BasicAuthIntegrationTest
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=23404, name=qtp1899892224-23404, state=TIMED_WAITING,
group=TGRP-BasicAuthIntegrationTest] 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) 2) Thread[id=23406,
name=qtp1899892224-23406, state=TIMED_WAITING,
group=TGRP-BasicAuthIntegrationTest] 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=23404, name=qtp1899892224-23404, state=TIMED_WAITING,
group=TGRP-BasicAuthIntegrationTest]
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)
2) Thread[id=23406, name=qtp1899892224-23406, state=TIMED_WAITING,
group=TGRP-BasicAuthIntegrationTest]
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([4052A8428686ABF4]:0)
Build Log:
[...truncated 13234 lines...]
[junit4] Suite: org.apache.solr.security.BasicAuthIntegrationTest
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/init-core-data-001
[junit4] 2> 2571393 WARN
(SUITE-BasicAuthIntegrationTest-seed#[4052A8428686ABF4]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 2571393 INFO
(SUITE-BasicAuthIntegrationTest-seed#[4052A8428686ABF4]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2571394 INFO
(SUITE-BasicAuthIntegrationTest-seed#[4052A8428686ABF4]-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> 2571394 INFO
(SUITE-BasicAuthIntegrationTest-seed#[4052A8428686ABF4]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2571395 INFO
(SUITE-BasicAuthIntegrationTest-seed#[4052A8428686ABF4]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001
[junit4] 2> 2571395 INFO
(SUITE-BasicAuthIntegrationTest-seed#[4052A8428686ABF4]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2571395 INFO (Thread-14285) [ ] o.a.s.c.ZkTestServer
client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2571395 INFO (Thread-14285) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2571408 ERROR (Thread-14285) [ ] 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> 2571495 INFO
(SUITE-BasicAuthIntegrationTest-seed#[4052A8428686ABF4]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:45282
[junit4] 2> 2571497 INFO (zkConnectionManagerCallback-2174-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2571499 INFO (jetty-launcher-2171-thread-3) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2571499 INFO (jetty-launcher-2171-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2571499 INFO (jetty-launcher-2171-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2571500 INFO (jetty-launcher-2171-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2571500 INFO (jetty-launcher-2171-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2571500 INFO (jetty-launcher-2171-thread-2) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 2571500 INFO (jetty-launcher-2171-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2571500 INFO (jetty-launcher-2171-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2571500 INFO (jetty-launcher-2171-thread-3) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 2571500 INFO (jetty-launcher-2171-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@747e57cd{/solr,null,AVAILABLE}
[junit4] 2> 2571500 INFO (jetty-launcher-2171-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2571500 INFO (jetty-launcher-2171-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2571501 INFO (jetty-launcher-2171-thread-1) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 2571501 INFO (jetty-launcher-2171-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@a74d161{/solr,null,AVAILABLE}
[junit4] 2> 2571501 INFO (jetty-launcher-2171-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@76788281{/solr,null,AVAILABLE}
[junit4] 2> 2571502 INFO (jetty-launcher-2171-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@543b8147{HTTP/1.1,[http/1.1]}{127.0.0.1:42689}
[junit4] 2> 2571502 INFO (jetty-launcher-2171-thread-2) [ ]
o.e.j.s.Server Started @2581219ms
[junit4] 2> 2571502 INFO (jetty-launcher-2171-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42689}
[junit4] 2> 2571502 ERROR (jetty-launcher-2171-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2571503 INFO (jetty-launcher-2171-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 2571503 INFO (jetty-launcher-2171-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2571503 INFO (jetty-launcher-2171-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2571503 INFO (jetty-launcher-2171-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-19T22:35:48.768Z
[junit4] 2> 2571504 INFO (zkConnectionManagerCallback-2176-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2571504 INFO (jetty-launcher-2171-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@11687fc4{HTTP/1.1,[http/1.1]}{127.0.0.1:37327}
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-1) [ ]
o.e.j.s.Server Started @2581238ms
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@563e0970{HTTP/1.1,[http/1.1]}{127.0.0.1:40480}
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37327}
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-3) [ ]
o.e.j.s.Server Started @2581238ms
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40480}
[junit4] 2> 2571522 ERROR (jetty-launcher-2171-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2571522 ERROR (jetty-launcher-2171-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-19T22:35:48.787Z
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 2571522 INFO (jetty-launcher-2171-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2571523 INFO (jetty-launcher-2171-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2571523 INFO (jetty-launcher-2171-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-19T22:35:48.788Z
[junit4] 2> 2571526 INFO (zkConnectionManagerCallback-2180-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2571526 INFO (jetty-launcher-2171-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2571526 INFO (jetty-launcher-2171-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45282/solr
[junit4] 2> 2571540 INFO (zkConnectionManagerCallback-2178-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2571562 INFO (jetty-launcher-2171-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2571563 INFO (zkConnectionManagerCallback-2184-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2571587 INFO
(zkConnectionManagerCallback-2186-thread-1-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2571588 INFO (jetty-launcher-2171-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45282/solr
[junit4] 2> 2571588 INFO (zkConnectionManagerCallback-2190-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2571589 INFO (jetty-launcher-2171-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45282/solr
[junit4] 2> 2571614 INFO (zkConnectionManagerCallback-2194-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2571646 INFO
(zkConnectionManagerCallback-2196-thread-1-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2571663 INFO
(zkConnectionManagerCallback-2199-thread-1-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2571671 INFO (jetty-launcher-2171-thread-1)
[n:127.0.0.1:37327_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2571671 INFO (jetty-launcher-2171-thread-3)
[n:127.0.0.1:40480_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2571688 INFO (jetty-launcher-2171-thread-3)
[n:127.0.0.1:40480_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:40480_solr
[junit4] 2> 2571689 INFO (jetty-launcher-2171-thread-1)
[n:127.0.0.1:37327_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37327_solr
[junit4] 2> 2571691 INFO (jetty-launcher-2171-thread-3)
[n:127.0.0.1:40480_solr ] o.a.s.c.Overseer Overseer
(id=99378878786633737-127.0.0.1:40480_solr-n_0000000000) starting
[junit4] 2> 2571708 INFO
(zkCallback-2198-thread-1-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2571709 INFO
(zkCallback-2195-thread-1-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2571737 INFO (jetty-launcher-2171-thread-3)
[n:127.0.0.1:40480_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40480_solr
[junit4] 2> 2571738 INFO
(zkCallback-2195-thread-1-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2571756 INFO
(zkCallback-2198-thread-1-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2571991 INFO (jetty-launcher-2171-thread-1)
[n:127.0.0.1:37327_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37327.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2572005 INFO (jetty-launcher-2171-thread-3)
[n:127.0.0.1:40480_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40480.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2572060 INFO (jetty-launcher-2171-thread-1)
[n:127.0.0.1:37327_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37327.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2572060 INFO (jetty-launcher-2171-thread-1)
[n:127.0.0.1:37327_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37327.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2572061 INFO (jetty-launcher-2171-thread-1)
[n:127.0.0.1:37327_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node1/.
[junit4] 2> 2572077 INFO (jetty-launcher-2171-thread-3)
[n:127.0.0.1:40480_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40480.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2572077 INFO (jetty-launcher-2171-thread-3)
[n:127.0.0.1:40480_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40480.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2572078 INFO (jetty-launcher-2171-thread-3)
[n:127.0.0.1:40480_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node3/.
[junit4] 2> 2572300 INFO (jetty-launcher-2171-thread-2)
[n:127.0.0.1:42689_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 2572301 INFO (jetty-launcher-2171-thread-2)
[n:127.0.0.1:42689_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2572315 INFO (jetty-launcher-2171-thread-2)
[n:127.0.0.1:42689_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42689_solr
[junit4] 2> 2572364 INFO
(zkCallback-2185-thread-1-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2572364 INFO
(zkCallback-2195-thread-1-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2572364 INFO
(zkCallback-2198-thread-1-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2572670 INFO (jetty-launcher-2171-thread-2)
[n:127.0.0.1:42689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42689.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2572705 INFO (jetty-launcher-2171-thread-2)
[n:127.0.0.1:42689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42689.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2572705 INFO (jetty-launcher-2171-thread-2)
[n:127.0.0.1:42689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42689.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2572706 INFO (jetty-launcher-2171-thread-2)
[n:127.0.0.1:42689_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node2/.
[junit4] 2> 2572861 INFO (zkConnectionManagerCallback-2206-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2572876 INFO (zkConnectionManagerCallback-2210-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2572876 INFO
(SUITE-BasicAuthIntegrationTest-seed#[4052A8428686ABF4]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2572877 INFO
(SUITE-BasicAuthIntegrationTest-seed#[4052A8428686ABF4]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45282/solr ready
[junit4] 2> 2572911 INFO (qtp1467945914-23403) [n:127.0.0.1:40480_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2572913 INFO
(OverseerThreadFactory-4460-thread-1-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
authCollection
[junit4] 2> 2573030 INFO
(OverseerStateUpdate-99378878786633737-127.0.0.1:40480_solr-n_0000000000)
[n:127.0.0.1:40480_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"authCollection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:42689/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2573032 INFO
(OverseerStateUpdate-99378878786633737-127.0.0.1:40480_solr-n_0000000000)
[n:127.0.0.1:40480_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"authCollection_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40480/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2573033 INFO
(OverseerStateUpdate-99378878786633737-127.0.0.1:40480_solr-n_0000000000)
[n:127.0.0.1:40480_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"authCollection_shard3_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37327/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2573281 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2573282 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2573282 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n2&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2573282 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2573306 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n4&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2573306 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2573425 INFO
(zkCallback-2198-thread-1-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2573425 INFO
(zkCallback-2185-thread-1-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2573426 INFO
(zkCallback-2185-thread-2-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2573426 INFO
(zkCallback-2198-thread-2-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2573427 INFO
(zkCallback-2195-thread-2-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2573427 INFO
(zkCallback-2195-thread-1-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2574419 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2574435 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2574455 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2574493 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.s.IndexSchema [authCollection_shard3_replica_n4] Schema name=minimal
[junit4] 2> 2574496 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2574496 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard3_replica_n4'
using configuration from collection authCollection, trusted=true
[junit4] 2> 2574497 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37327.solr.core.authCollection.shard3.replica_n4' (registry
'solr.core.authCollection.shard3.replica_n4') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2574497 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2574497 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.SolrCore [[authCollection_shard3_replica_n4] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node1/authCollection_shard3_replica_n4],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node1/./authCollection_shard3_replica_n4/data/]
[junit4] 2> 2574510 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.s.IndexSchema [authCollection_shard2_replica_n2] Schema name=minimal
[junit4] 2> 2574512 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2574512 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard2_replica_n2'
using configuration from collection authCollection, trusted=true
[junit4] 2> 2574512 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40480.solr.core.authCollection.shard2.replica_n2' (registry
'solr.core.authCollection.shard2.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2574513 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2574513 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.SolrCore [[authCollection_shard2_replica_n2] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node3/authCollection_shard2_replica_n2],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node3/./authCollection_shard2_replica_n2/data/]
[junit4] 2> 2574519 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.s.IndexSchema [authCollection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2574520 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2574520 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard1_replica_n1'
using configuration from collection authCollection, trusted=true
[junit4] 2> 2574521 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42689.solr.core.authCollection.shard1.replica_n1' (registry
'solr.core.authCollection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2574521 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2574521 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.SolrCore [[authCollection_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node2/authCollection_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node2/./authCollection_shard1_replica_n1/data/]
[junit4] 2> 2574865 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2574865 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2574866 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2574866 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2574879 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2574879 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2574880 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2574880 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2574881 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@6fe68878[authCollection_shard1_replica_n1] main]
[junit4] 2> 2574886 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2574887 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2574887 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2574887 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1590062064234135552
[junit4] 2> 2574899 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@35c32c72[authCollection_shard2_replica_n2] main]
[junit4] 2> 2574903 INFO
(searcherExecutor-4469-thread-1-processing-n:127.0.0.1:42689_solr
x:authCollection_shard1_replica_n1 s:shard1 c:authCollection r:core_node3)
[n:127.0.0.1:42689_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore
[authCollection_shard1_replica_n1] Registered new searcher
Searcher@6fe68878[authCollection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2574904 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2574904 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2574904 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:42689/solr/authCollection_shard1_replica_n1/
[junit4] 2> 2574904 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2574904 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.SyncStrategy
http://127.0.0.1:42689/solr/authCollection_shard1_replica_n1/ has no replicas
[junit4] 2> 2574904 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2574906 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:42689/solr/authCollection_shard1_replica_n1/ shard1
[junit4] 2> 2574935 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2574935 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2574936 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2574936 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1590062064285515776
[junit4] 2> 2574938 INFO
(searcherExecutor-4468-thread-1-processing-n:127.0.0.1:40480_solr
x:authCollection_shard2_replica_n2 s:shard2 c:authCollection r:core_node5)
[n:127.0.0.1:40480_solr c:authCollection s:shard2 r:core_node5
x:authCollection_shard2_replica_n2] o.a.s.c.SolrCore
[authCollection_shard2_replica_n2] Registered new searcher
Searcher@35c32c72[authCollection_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2574968 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2574969 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2574969 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:40480/solr/authCollection_shard2_replica_n2/
[junit4] 2> 2574969 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2574969 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.SyncStrategy
http://127.0.0.1:40480/solr/authCollection_shard2_replica_n2/ has no replicas
[junit4] 2> 2574969 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2574970 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:40480/solr/authCollection_shard2_replica_n2/ shard2
[junit4] 2> 2574991 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2574991 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2575008 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2575008 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2575009 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@424bca80[authCollection_shard3_replica_n4] main]
[junit4] 2> 2575010 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2575010 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2575011 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2575011 INFO
(searcherExecutor-4467-thread-1-processing-n:127.0.0.1:37327_solr
x:authCollection_shard3_replica_n4 s:shard3 c:authCollection r:core_node6)
[n:127.0.0.1:37327_solr c:authCollection s:shard3 r:core_node6
x:authCollection_shard3_replica_n4] o.a.s.c.SolrCore
[authCollection_shard3_replica_n4] Registered new searcher
Searcher@424bca80[authCollection_shard3_replica_n4]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2575011 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1590062064364158976
[junit4] 2> 2575022 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2575022 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2575022 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:37327/solr/authCollection_shard3_replica_n4/
[junit4] 2> 2575022 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2575022 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.SyncStrategy
http://127.0.0.1:37327/solr/authCollection_shard3_replica_n4/ has no replicas
[junit4] 2> 2575022 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2575024 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:37327/solr/authCollection_shard3_replica_n4/ shard3
[junit4] 2> 2575186 INFO
(zkCallback-2198-thread-2-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2575190 INFO
(zkCallback-2185-thread-2-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2575190 INFO
(zkCallback-2185-thread-1-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2575202 INFO
(zkCallback-2195-thread-2-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2575202 INFO
(zkCallback-2198-thread-1-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2575202 INFO
(zkCallback-2195-thread-1-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2575222 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2575223 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2575224 INFO (qtp1899892224-23400) [n:127.0.0.1:42689_solr
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1942
[junit4] 2> 2575243 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n2&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1960
[junit4] 2> 2575252 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2575259 INFO (qtp908173573-23409) [n:127.0.0.1:37327_solr
c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n4&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1953
[junit4] 2> 2575389 INFO (qtp1467945914-23403) [n:127.0.0.1:40480_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> 2575422 INFO
(zkCallback-2198-thread-3-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2575422 INFO
(zkCallback-2198-thread-1-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2575425 INFO
(zkCallback-2185-thread-2-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2575429 INFO
(zkCallback-2185-thread-1-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2575437 INFO
(zkCallback-2195-thread-1-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2575437 INFO
(zkCallback-2195-thread-2-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2576393 INFO (qtp1467945914-23403) [n:127.0.0.1:40480_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2}
status=0 QTime=3481
[junit4] 2> 2576439 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testBasicAuth
[junit4] 2> 2576441 INFO (qtp1899892224-23406) [n:127.0.0.1:42689_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/cluster/security/authentication params={} status=0 QTime=0
[junit4] 2> 2576454 INFO (qtp1899892224-23407) [n:127.0.0.1:42689_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/cluster/security/authentication params={} status=0 QTime=0
[junit4] 2> 2576490 INFO
(zkCallback-2198-thread-2-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.CoreContainer Security node changed,
reloading security.json
[junit4] 2> 2576490 INFO
(zkCallback-2198-thread-2-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.CoreContainer Initializing authorization
plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2576502 INFO
(zkCallback-2185-thread-1-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.CoreContainer Security node changed,
reloading security.json
[junit4] 2> 2576502 INFO
(zkCallback-2195-thread-2-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.CoreContainer Security node changed,
reloading security.json
[junit4] 2> 2576502 INFO
(zkCallback-2185-thread-1-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.CoreContainer Initializing authorization
plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2576502 INFO
(zkCallback-2195-thread-2-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.CoreContainer Initializing authorization
plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2576509 INFO (qtp1899892224-23406) [n:127.0.0.1:42689_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/cluster/security/authentication params={} status=0 QTime=0
[junit4] 2> 2576563 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@543b8147{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2576588 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1692239209
[junit4] 2> 2576594 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 2576594 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@79ed13dd: rootName =
solr_42689, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@4c4924e0
[junit4] 2> 2576617 INFO
(zkCallback-2185-thread-1-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.CoreContainer Initializing authentication
plugin: solr.BasicAuthPlugin
[junit4] 2> 2576618 INFO
(zkCallback-2198-thread-2-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.CoreContainer Initializing authentication
plugin: solr.BasicAuthPlugin
[junit4] 2> 2576619 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 2576619 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@789f8cf1: rootName =
solr_42689, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@589d162d
[junit4] 2> 2576620 INFO
(zkCallback-2195-thread-2-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.CoreContainer Initializing authentication
plugin: solr.BasicAuthPlugin
[junit4] 2> 2576647 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 2576647 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1f595c57: rootName =
solr_42689, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@5ca289e6
[junit4] 2> 2576661 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:42689_solr
[junit4] 2> 2576662 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 2576662 INFO (zkCallback-2209-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 2576674 INFO (coreCloseExecutor-4482-thread-1)
[n:127.0.0.1:42689_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore
[authCollection_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@66c29b73
[junit4] 2> 2576674 INFO (coreCloseExecutor-4482-thread-1)
[n:127.0.0.1:42689_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.authCollection.shard1.replica_n1,
tag=1724029811
[junit4] 2> 2576674 INFO (coreCloseExecutor-4482-thread-1)
[n:127.0.0.1:42689_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6b8f2348: rootName =
solr_42689, domain = solr.core.authCollection.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.authCollection.shard1.replica_n1
/ com.codahale.metrics.MetricRegistry@bbbcbd2
[junit4] 2> 2576701 INFO (coreCloseExecutor-4482-thread-1)
[n:127.0.0.1:42689_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.authCollection.shard1.leader,
tag=1724029811
[junit4] 2> 2576748 INFO
(zkCallback-2195-thread-2-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is
managing internode requests
[junit4] 2> 2576748 INFO
(zkCallback-2198-thread-2-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is
managing internode requests
[junit4] 2> 2576748 INFO
(zkCallback-2198-thread-3-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 2576748 INFO
(zkCallback-2185-thread-1-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is
managing internode requests
[junit4] 2> 2576748 INFO
(zkCallback-2185-thread-2-processing-n:127.0.0.1:42689_solr)
[n:127.0.0.1:42689_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 2576748 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@747e57cd{/solr,null,UNAVAILABLE}
[junit4] 2> 2576749 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 2576765 INFO
(zkCallback-2195-thread-1-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 2576766 INFO
(zkCallback-2198-thread-2-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2576766 INFO
(zkCallback-2198-thread-3-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2576766 INFO
(zkCallback-2195-thread-2-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2576766 INFO
(zkCallback-2195-thread-3-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2576948 INFO
(OverseerCollectionConfigSetProcessor-99378878786633737-127.0.0.1:40480_solr-n_0000000000)
[n:127.0.0.1:40480_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> 2606748 WARN
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.u.t.QueuedThreadPool
QueuedThreadPool@qtp1899892224{STOPPING,8<=9<=10000,i=0,q=2} Couldn't stop
Thread[qtp1899892224-23404,5,TGRP-BasicAuthIntegrationTest]
[junit4] 2> 2606748 WARN
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.u.t.QueuedThreadPool
QueuedThreadPool@qtp1899892224{STOPPING,8<=9<=10000,i=0,q=2} Couldn't stop
Thread[qtp1899892224-23406,5,TGRP-BasicAuthIntegrationTest]
[junit4] 2> 2606749 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2606749 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2606749 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2606749 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2606754 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@ad3f498{/solr,null,AVAILABLE}
[junit4] 2> 2606754 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5d94b8bb{HTTP/1.1,[http/1.1]}{127.0.0.1:33116}
[junit4] 2> 2606754 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.e.j.s.Server Started @2616471ms
[junit4] 2> 2606754 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33116}
[junit4] 2> 2606754 ERROR
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2606754 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 2606754 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2606754 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2606755 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-19T22:36:24.020Z
[junit4] 2> 2606756 INFO (zkConnectionManagerCallback-2212-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2606756 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2606779 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45282/solr
[junit4] 2> 2606794 INFO (zkConnectionManagerCallback-2216-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2606796 INFO
(zkConnectionManagerCallback-2218-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2606813 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4])
[n:127.0.0.1:33116_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 2606814 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4])
[n:127.0.0.1:33116_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2606815 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4])
[n:127.0.0.1:33116_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33116_solr
[junit4] 2> 2606816 INFO
(zkCallback-2198-thread-3-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2606816 INFO (zkCallback-2209-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2606822 INFO
(zkCallback-2195-thread-3-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2606838 INFO
(zkCallback-2217-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 2606846 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4])
[n:127.0.0.1:33116_solr ] o.a.s.c.CoreContainer Initializing authorization
plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2606846 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4])
[n:127.0.0.1:33116_solr ] o.a.s.c.CoreContainer Initializing authentication
plugin: solr.BasicAuthPlugin
[junit4] 2> 2606846 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4])
[n:127.0.0.1:33116_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is
managing internode requests
[junit4] 2> 2606912 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4])
[n:127.0.0.1:33116_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33116.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2606920 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4])
[n:127.0.0.1:33116_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33116.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2606921 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4])
[n:127.0.0.1:33116_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33116.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2606922 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4])
[n:127.0.0.1:33116_solr ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node2/.
[junit4] 2> 2606922 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4])
[n:127.0.0.1:33116_solr ] o.a.s.c.CorePropertiesLocator Cores are:
[authCollection_shard1_replica_n1]
[junit4] 2> 2606923 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 2606928 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2606934 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr ] o.a.s.s.IndexSchema
[authCollection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2606952 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with
uniqueid field id
[junit4] 2> 2606954 INFO
(zkCallback-2195-thread-2-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2606954 INFO
(zkCallback-2195-thread-3-processing-n:127.0.0.1:37327_solr)
[n:127.0.0.1:37327_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2606954 INFO
(zkCallback-2198-thread-3-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2606954 INFO
(zkCallback-2217-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2606954 INFO
(zkCallback-2198-thread-2-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2606954 INFO
(zkCallback-2217-thread-2-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2607058 INFO (ScheduledTrigger-4458-thread-1)
[n:127.0.0.1:40480_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 2607071 INFO (qtp1488666744-23520) [n:127.0.0.1:33116_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key
params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 2607071 INFO (qtp1488666744-23516) [ ]
o.a.s.s.PKIAuthenticationPlugin New Key obtained from node:
127.0.0.1:33116_solr /
MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDCxq+YGTekUdhp/LQUJ0eXmqyDqZ3oTtemZSSOoFNdg21nV27wuXNNlK8AuvphWz0umuTB4OMdZpxcOrzMAxRCBjBktqRiZqfacYsLge42ARA6Afl0h3y1nDwpp9v0GiB8tXp2vgQr5jAKLdEDniKifjt85QoPiqJESVutVbLSpwIDAQAB
[junit4] 2> 2607085 INFO (qtp1488666744-23516) [n:127.0.0.1:33116_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={wt=javabin&version=2&key=solr.core.authCollection.shard1.replica_n1:INDEX.sizeInBytes}
status=0 QTime=0
[junit4] 2> 2607088 INFO (qtp1488666744-23518) [n:127.0.0.1:33116_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2607090 INFO (qtp1488666744-23518) [n:127.0.0.1:33116_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key
params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 2607090 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2]
o.a.s.s.PKIAuthenticationPlugin New Key obtained from node:
127.0.0.1:33116_solr /
MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDCxq+YGTekUdhp/LQUJ0eXmqyDqZ3oTtemZSSOoFNdg21nV27wuXNNlK8AuvphWz0umuTB4OMdZpxcOrzMAxRCBjBktqRiZqfacYsLge42ARA6Afl0h3y1nDwpp9v0GiB8tXp2vgQr5jAKLdEDniKifjt85QoPiqJESVutVbLSpwIDAQAB
[junit4] 2> 2607102 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={wt=javabin&version=2&key=solr.core.authCollection.shard2.replica_n2:INDEX.sizeInBytes}
status=0 QTime=0
[junit4] 2> 2607105 INFO (qtp1467945914-23411) [n:127.0.0.1:40480_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2607107 INFO (qtp1488666744-23518) [n:127.0.0.1:33116_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key
params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 2607107 INFO (qtp908173573-23420) [ ]
o.a.s.s.PKIAuthenticationPlugin New Key obtained from node:
127.0.0.1:33116_solr /
MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDCxq+YGTekUdhp/LQUJ0eXmqyDqZ3oTtemZSSOoFNdg21nV27wuXNNlK8AuvphWz0umuTB4OMdZpxcOrzMAxRCBjBktqRiZqfacYsLge42ARA6Afl0h3y1nDwpp9v0GiB8tXp2vgQr5jAKLdEDniKifjt85QoPiqJESVutVbLSpwIDAQAB
[junit4] 2> 2607130 INFO (qtp908173573-23420) [n:127.0.0.1:37327_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={wt=javabin&version=2&key=solr.core.authCollection.shard3.replica_n4:INDEX.sizeInBytes}
status=0 QTime=22
[junit4] 2> 2607132 INFO (qtp908173573-23401) [n:127.0.0.1:37327_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2607132 INFO
(AutoscalingActionExecutor-4459-thread-1-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession,
curr-time 855716390 sessionWrapper.createTime 855716390780628,
this.sessionWrapper.createTime 855716390780628
[junit4] 2> 2607132 INFO
(AutoscalingActionExecutor-4459-thread-1-processing-n:127.0.0.1:40480_solr)
[n:127.0.0.1:40480_solr ] o.a.s.c.a.ExecutePlanAction No operations to
execute for event: {
[junit4] 2> "id":"30a3dfacbc333T254k4hywiotxkiquobhi1xfua",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":855686247072563,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[855686247072563],
[junit4] 2> "_enqueue_time_":855716316258922,
[junit4] 2> "nodeNames":["127.0.0.1:42689_solr"]}}
[junit4] 2> 2607959 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 8.0.0
[junit4] 2> 2607964 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.s.IndexSchema
[authCollection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2607974 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 2607974 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore
'authCollection_shard1_replica_n1' using configuration from collection
authCollection, trusted=true
[junit4] 2> 2607974 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring
for 'solr_33116.solr.core.authCollection.shard1.replica_n1' (registry
'solr.core.authCollection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@69b19b0c
[junit4] 2> 2607975 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2607975 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore
[[authCollection_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node2/authCollection_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.security.BasicAuthIntegrationTest_4052A8428686ABF4-001/tempDir-001/node2/./authCollection_shard1_replica_n1/data/]
[junit4] 2> 2608005 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2608005 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 2608006 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 2608006 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 2608007 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@4c21408[authCollection_shard1_replica_n1] main]
[junit4] 2> 2608007 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2608007 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null
at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2608008 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000ms.
[junit4] 2> 2608008 INFO
(searcherExecutor-4490-thread-1-processing-n:127.0.0.1:33116_solr
x:authCollection_shard1_replica_n1 s:shard1 c:authCollection r:core_node3)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore
[authCollection_shard1_replica_n1] Registered new searcher
Searcher@4c21408[authCollection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2608009 INFO
(coreLoadExecutor-4489-thread-1-processing-n:127.0.0.1:33116_solr)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1590062098964021248
[junit4] 2> 2608011 INFO
(coreZkRegister-4484-thread-1-processing-n:127.0.0.1:33116_solr
x:authCollection_shard1_replica_n1 s:shard1 c:authCollection r:core_node3)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 2608011 INFO
(coreZkRegister-4484-thread-1-processing-n:127.0.0.1:33116_solr
x:authCollection_shard1_replica_n1 s:shard1 c:authCollection r:core_node3)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be
the new leader - try and sync
[junit4] 2> 2608011 INFO
(coreZkRegister-4484-thread-1-processing-n:127.0.0.1:33116_solr
x:authCollection_shard1_replica_n1 s:shard1 c:authCollection r:core_node3)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:33116/solr/authCollection_shard1_replica_n1/
[junit4] 2> 2608012 INFO
(coreZkRegister-4484-thread-1-processing-n:127.0.0.1:33116_solr
x:authCollection_shard1_replica_n1 s:shard1 c:authCollection r:core_node3)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 2608012 INFO
(coreZkRegister-4484-thread-1-processing-n:127.0.0.1:33116_solr
x:authCollection_shard1_replica_n1 s:shard1 c:authCollection r:core_node3)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy
http://127.0.0.1:33116/solr/authCollection_shard1_replica_n1/ has no replicas
[junit4] 2> 2608012 INFO
(coreZkRegister-4484-thread-1-processing-n:127.0.0.1:33116_solr
x:authCollection_shard1_replica_n1 s:shard1 c:authCollection r:core_node3)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found
all replicas participating in election, clear LIR
[junit4] 2> 2608012 INFO (qtp1488666744-23518) [n:127.0.0.1:33116_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/cluster/security/authentication params={} status=0 QTime=0
[junit4] 2> 2608012 INFO
(coreZkRegister-4484-thread-1-processing-n:127.0.0.1:33116_solr
x:authCollection_shard1_replica_n1 s:shard1 c:authCollection r:core_node3)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the
new leader: http://127.0.0.1:33116/solr/authCollection_shard1_replica_n1/ shard1
[junit4] 2> 2608013 INFO
(coreZkRegister-4484-thread-1-processing-n:127.0.0.1:33116_solr
x:authCollection_shard1_replica_n1 s:shard1 c:authCollection r:core_node3)
[n:127.0.0.1:33116_solr c:authCollection s:shard1 r:core_node3
x:authCollection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 2608014 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
] o.a.s.s.RuleBasedAuthorizationPlugin request has come without principal.
failed permission {
[junit4] 2> "name":"security-edit",
[junit4] 2> "role":"admin"}
[junit4] 2> 2608014 INFO (qtp1467945914-23415) [n:127.0.0.1:40480_solr
] o.a.s.s.HttpSolrCall USER_REQUIRED auth header null context : userPrincipal:
[null] type: [ADMIN], collections: [], Path:
[/____v2/cluster/security/authentication] path :
/____v2/cluster/security/authentication params :wt=javabin&version=2
[junit4] 2> 2608032 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[4052A8428686ABF4]) [ ]
o.a.s.s.Basi
[...truncated too long message...]
D_WAITING, group=TGRP-BasicAuthIntegrationTest]
[junit4] 2> Jan 19, 2018 10:36:50 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be
terminated:
[junit4] 2> 1) Thread[id=23404, name=qtp1899892224-23404,
state=TIMED_WAITING, group=TGRP-BasicAuthIntegrationTest]
[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> 2) Thread[id=23406, name=qtp1899892224-23406,
state=TIMED_WAITING, group=TGRP-BasicAuthIntegrationTest]
[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):
{id=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{},
maxPointsInLeafNode=1531, maxMBSortInHeap=6.940764471181694,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4e54f474),
locale=cs-CZ, timezone=America/Miquelon
[junit4] 2> NOTE: Linux 4.4.0-104-generic amd64/Oracle Corporation
1.8.0_152 (64-bit)/cpus=4,threads=3,free=59338728,total=525860864
[junit4] 2> NOTE: All tests run in this JVM: [TestCoreBackup,
SpellCheckComponentTest, DocValuesMultiTest, HdfsRestartWhileUpdatingTest,
LukeRequestHandlerTest, MultiTermTest, NotRequiredUniqueKeyTest,
TestJsonFacetRefinement, CollectionsAPISolrJTest, TestReqParamsAPI,
TestBulkSchemaConcurrent, TestManagedSchemaAPI, TestSimpleTextCodec,
TestSystemCollAutoCreate, TestExactSharedStatsCache, SolrCmdDistributorTest,
TestCloudPseudoReturnFields, JvmMetricsTest, ClassificationUpdateProcessorTest,
TestRealTimeGet, DistributedDebugComponentTest,
BigEndianAscendingWordDeserializerTest, TestXmlQParser,
TestJavabinTupleStreamParser, DistributedFacetPivotSmallTest,
TestSolrCoreProperties, TestDocBasedVersionConstraints, TestStressReorder,
CreateCollectionCleanupTest, PropertiesRequestHandlerTest, NoCacheHeaderTest,
NumericFieldsTest, TestAddFieldRealTimeGet,
VMParamsZkACLAndCredentialsProvidersTest, TestDynamicFieldCollectionResource,
StatsReloadRaceTest, TestLeaderElectionWithEmptyReplica, TestRawTransformer,
StatelessScriptUpdateProcessorFactoryTest, ReplaceNodeTest, SpatialFilterTest,
AddBlockUpdateTest, TestDistributedMissingSort, SearchHandlerTest,
TestLMJelinekMercerSimilarityFactory, TestShortCircuitedRequests,
URLClassifyProcessorTest, SolrGangliaReporterTest,
TestSolrCloudWithDelegationTokens, NodeLostTriggerTest,
BasicDistributedZk2Test, DistanceUnitsTest, CollectionReloadTest,
SuggesterTSTTest, TestBadConfig, HdfsCollectionsAPIDistributedZkTest,
AliasIntegrationTest, TermVectorComponentTest, TestFiltering, CircularListTest,
TestCorePropertiesReload, TestComplexPhraseQParserPlugin,
TestComputePlanAction, RegexBoostProcessorTest, StressHdfsTest,
ReturnFieldsTest, DistribJoinFromCollectionTest, TestSolrCloudSnapshots,
TestObjectReleaseTracker, GraphQueryTest, AutoAddReplicasIntegrationTest,
PingRequestHandlerTest, TestNodeLostTrigger, ComputePlanActionTest,
MoreLikeThisHandlerTest, TestSchemaNameResource, TestScoreJoinQPNoScore,
SpatialHeatmapFacetsTest, TestNRTOpen, TestEmbeddedSolrServerConstructors,
TestCollectionAPI, LeaderElectionIntegrationTest, TestFreeTextSuggestions,
TestAuthorizationFramework, TestStandardQParsers, TestLegacyTerms,
BasicAuthIntegrationTest]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=BasicAuthIntegrationTest -Dtests.seed=4052A8428686ABF4
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
-Dtests.locale=cs-CZ -Dtests.timezone=America/Miquelon -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J2 | BasicAuthIntegrationTest (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.security.BasicAuthIntegrationTest:
[junit4] > 1) Thread[id=23404, name=qtp1899892224-23404,
state=TIMED_WAITING, group=TGRP-BasicAuthIntegrationTest]
[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] > 2) Thread[id=23406, name=qtp1899892224-23406,
state=TIMED_WAITING, group=TGRP-BasicAuthIntegrationTest]
[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([4052A8428686ABF4]:0)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=23404, name=qtp1899892224-23404,
state=TIMED_WAITING, group=TGRP-BasicAuthIntegrationTest]
[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] > 2) Thread[id=23406, name=qtp1899892224-23406,
state=TIMED_WAITING, group=TGRP-BasicAuthIntegrationTest]
[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([4052A8428686ABF4]:0)
[junit4] Completed [245/768 (1!)] on J2 in 62.42s, 1 test, 2 errors <<<
FAILURES!
[...truncated 49665 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
