Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1070/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth

Error Message:
must have failed

Stack Trace:
java.lang.AssertionError: must have failed
        at 
__randomizedtesting.SeedInfo.seed([476CF8A00ED578A9:FB028EB2AA86FBD3]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:159)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 12733 lines...]
   [junit4] Suite: org.apache.solr.security.BasicAuthIntegrationTest
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/init-core-data-001
   [junit4]   2> 2598954 INFO  
(SUITE-BasicAuthIntegrationTest-seed#[476CF8A00ED578A9]-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> 2598954 INFO  
(SUITE-BasicAuthIntegrationTest-seed#[476CF8A00ED578A9]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001
   [junit4]   2> 2598955 INFO  
(SUITE-BasicAuthIntegrationTest-seed#[476CF8A00ED578A9]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2598955 INFO  (Thread-6627) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2598955 INFO  (Thread-6627) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2599055 INFO  
(SUITE-BasicAuthIntegrationTest-seed#[476CF8A00ED578A9]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:51250
   [junit4]   2> 2599062 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2599062 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2599062 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2599065 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6af6f7f2{/solr,null,AVAILABLE}
   [junit4]   2> 2599065 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5e4a047c{/solr,null,AVAILABLE}
   [junit4]   2> 2599066 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@f199c7{HTTP/1.1,[http/1.1]}{127.0.0.1:62256}
   [junit4]   2> 2599066 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@45d7da13{HTTP/1.1,[http/1.1]}{127.0.0.1:54752}
   [junit4]   2> 2599066 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.e.j.s.Server Started @2603174ms
   [junit4]   2> 2599066 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.e.j.s.Server Started @2603174ms
   [junit4]   2> 2599066 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=62256}
   [junit4]   2> 2599066 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=54752}
   [junit4]   2> 2599067 ERROR (jetty-launcher-4296-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2599067 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@490a6e4e{/solr,null,AVAILABLE}
   [junit4]   2> 2599067 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 2599067 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2599067 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2599067 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-10T17:13:03.349Z
   [junit4]   2> 2599067 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@75276fb1{HTTP/1.1,[http/1.1]}{127.0.0.1:55792}
   [junit4]   2> 2599067 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.e.j.s.Server Started @2603175ms
   [junit4]   2> 2599067 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=55792}
   [junit4]   2> 2599067 ERROR (jetty-launcher-4296-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2599068 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 2599068 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2599068 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2599068 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-10T17:13:03.350Z
   [junit4]   2> 2599067 ERROR (jetty-launcher-4296-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2599069 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 2599069 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2599069 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2599069 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-10T17:13:03.351Z
   [junit4]   2> 2599072 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2599072 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2599072 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2599079 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51250/solr
   [junit4]   2> 2599080 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x159895e6a060003, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2599086 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51250/solr
   [junit4]   2> 2599086 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x159895e6a060002, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2599088 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51250/solr
   [junit4]   2> 2599119 INFO  (jetty-launcher-4296-thread-1) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:62256_solr
   [junit4]   2> 2599120 INFO  (jetty-launcher-4296-thread-3) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:54752_solr
   [junit4]   2> 2599121 INFO  (jetty-launcher-4296-thread-1) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.Overseer Overseer 
(id=97259905563885575-127.0.0.1:62256_solr-n_0000000000) starting
   [junit4]   2> 2599122 INFO  (jetty-launcher-4296-thread-2) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:55792_solr
   [junit4]   2> 2599123 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2599123 INFO  
(zkCallback-4310-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2599124 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2599130 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 2599131 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 2599140 INFO  
(zkCallback-4310-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 2599161 INFO  (jetty-launcher-4296-thread-1) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62256_solr
   [junit4]   2> 2599173 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2599173 INFO  
(zkCallback-4310-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2599173 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2599194 INFO  (jetty-launcher-4296-thread-2) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node2/.
   [junit4]   2> 2599219 INFO  (jetty-launcher-4296-thread-1) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node1/.
   [junit4]   2> 2599348 INFO  (jetty-launcher-4296-thread-3) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node3/.
   [junit4]   2> 2600597 INFO  
(SUITE-BasicAuthIntegrationTest-seed#[476CF8A00ED578A9]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 2600599 INFO  
(SUITE-BasicAuthIntegrationTest-seed#[476CF8A00ED578A9]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:51250/solr ready
   [junit4]   2> 2600606 INFO  (qtp604281198-24941) [n:127.0.0.1:54752_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=authCollection&action=CREATE&numShards=3&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2600609 INFO  
(OverseerThreadFactory-9775-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
authCollection
   [junit4]   2> 2600719 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica1&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2
   [junit4]   2> 2600719 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica1&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2
   [junit4]   2> 2600721 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2
   [junit4]   2> 2600833 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_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> 2600833 INFO  
(zkCallback-4310-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_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> 2600833 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_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> 2601734 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2601736 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2601745 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2601750 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.s.IndexSchema [authCollection_shard2_replica1] Schema name=minimal
   [junit4]   2> 2601753 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 2601754 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.s.IndexSchema [authCollection_shard1_replica1] Schema name=minimal
   [junit4]   2> 2601754 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard2_replica1' using 
configuration from collection authCollection
   [junit4]   2> 2601755 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] o.a.s.c.SolrCore 
[[authCollection_shard2_replica1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node1/authCollection_shard2_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node1/./authCollection_shard2_replica1/data/]
   [junit4]   2> 2601756 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.s.IndexSchema [authCollection_shard3_replica1] Schema name=minimal
   [junit4]   2> 2601757 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 2601759 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard1_replica1' using 
configuration from collection authCollection
   [junit4]   2> 2601759 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 2601759 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] o.a.s.c.SolrCore 
[[authCollection_shard1_replica1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node2/authCollection_shard1_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node2/./authCollection_shard1_replica1/data/]
   [junit4]   2> 2601760 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard3_replica1' using 
configuration from collection authCollection
   [junit4]   2> 2601760 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] o.a.s.c.SolrCore 
[[authCollection_shard3_replica1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node3/authCollection_shard3_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node3/./authCollection_shard3_replica1/data/]
   [junit4]   2> 2601770 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2601770 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2601772 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2601772 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2601773 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@13189788[authCollection_shard2_replica1] main]
   [junit4]   2> 2601774 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2601774 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2601774 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2601775 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2601775 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2601776 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1556158491979153408
   [junit4]   2> 2601776 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2601776 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2601776 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7adc86c4[authCollection_shard1_replica1] main]
   [junit4]   2> 2601777 INFO  
(searcherExecutor-9782-thread-1-processing-n:127.0.0.1:62256_solr 
x:authCollection_shard2_replica1 s:shard2 c:authCollection) 
[n:127.0.0.1:62256_solr c:authCollection s:shard2  
x:authCollection_shard2_replica1] o.a.s.c.SolrCore 
[authCollection_shard2_replica1] Registered new searcher 
Searcher@13189788[authCollection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2601778 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2601778 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2601779 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2601779 INFO  
(searcherExecutor-9783-thread-1-processing-n:127.0.0.1:55792_solr 
x:authCollection_shard1_replica1 s:shard1 c:authCollection) 
[n:127.0.0.1:55792_solr c:authCollection s:shard1  
x:authCollection_shard1_replica1] o.a.s.c.SolrCore 
[authCollection_shard1_replica1] Registered new searcher 
Searcher@7adc86c4[authCollection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2601779 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2601779 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2601779 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1556158491982299136
   [junit4]   2> 2601782 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2601782 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2601783 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1f0b00c2[authCollection_shard3_replica1] main]
   [junit4]   2> 2601785 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2601787 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2601788 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2601788 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:62256/solr/authCollection_shard2_replica1/
   [junit4]   2> 2601789 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2601789 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:62256/solr/authCollection_shard2_replica1/ has no replicas
   [junit4]   2> 2601788 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2601789 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2601789 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1556158491992784896
   [junit4]   2> 2601790 INFO  
(searcherExecutor-9784-thread-1-processing-n:127.0.0.1:54752_solr 
x:authCollection_shard3_replica1 s:shard3 c:authCollection) 
[n:127.0.0.1:54752_solr c:authCollection s:shard3  
x:authCollection_shard3_replica1] o.a.s.c.SolrCore 
[authCollection_shard3_replica1] Registered new searcher 
Searcher@1f0b00c2[authCollection_shard3_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2601792 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2601792 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2601792 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:55792/solr/authCollection_shard1_replica1/
   [junit4]   2> 2601792 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2601792 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:55792/solr/authCollection_shard1_replica1/ has no replicas
   [junit4]   2> 2601798 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:62256/solr/authCollection_shard2_replica1/ shard2
   [junit4]   2> 2601798 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2601798 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2601798 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:54752/solr/authCollection_shard3_replica1/
   [junit4]   2> 2601798 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2601798 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:54752/solr/authCollection_shard3_replica1/ has no replicas
   [junit4]   2> 2601799 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:55792/solr/authCollection_shard1_replica1/ shard1
   [junit4]   2> 2601804 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:54752/solr/authCollection_shard3_replica1/ shard3
   [junit4]   2> 2601912 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_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> 2601912 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_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> 2601912 INFO  
(zkCallback-4310-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_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> 2601950 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2601950 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2601953 INFO  (qtp1123946935-24924) [n:127.0.0.1:62256_solr 
c:authCollection s:shard2  x:authCollection_shard2_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica1&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2}
 status=0 QTime=1234
   [junit4]   2> 2601954 INFO  (qtp1000205469-24928) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1  x:authCollection_shard1_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2}
 status=0 QTime=1233
   [junit4]   2> 2601959 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2601962 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3  x:authCollection_shard3_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica1&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2}
 status=0 QTime=1243
   [junit4]   2> 2601966 INFO  (qtp604281198-24941) [n:127.0.0.1:54752_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> 2602068 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_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> 2602068 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_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> 2602068 INFO  
(zkCallback-4310-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_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> 2602620 INFO  
(OverseerCollectionConfigSetProcessor-97259905563885575-127.0.0.1:62256_solr-n_0000000000)
 [n:127.0.0.1:62256_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> 2602967 INFO  (qtp604281198-24941) [n:127.0.0.1:54752_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=authCollection&action=CREATE&numShards=3&wt=javabin&version=2}
 status=0 QTime=2362
   [junit4]   2> 2603003 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testBasicAuth
   [junit4]   2> 2603006 INFO  (qtp1123946935-24926) [n:127.0.0.1:62256_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} 
status=0 QTime=0
   [junit4]   2> 2603008 INFO  (qtp1123946935-24927) [n:127.0.0.1:62256_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} 
status=0 QTime=0
   [junit4]   2> 2603009 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2603009 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2603009 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2603009 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2603009 INFO  
(zkCallback-4310-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2603009 INFO  
(zkCallback-4310-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2603009 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2603009 INFO  
(zkCallback-4310-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2603009 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2603009 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is 
managing internode requests
   [junit4]   2> 2603009 INFO  
(zkCallback-4310-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is 
managing internode requests
   [junit4]   2> 2603009 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is 
managing internode requests
   [junit4]   2> 2603059 INFO  (qtp1123946935-24937) [n:127.0.0.1:62256_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} 
status=0 QTime=0
   [junit4]   2> 2603061 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@f199c7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2603061 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=156300666
   [junit4]   2> 2603064 INFO  (coreCloseExecutor-9797-thread-1) 
[n:127.0.0.1:62256_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.SolrCore 
[authCollection_shard2_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@721ee45b
   [junit4]   2> 2603065 INFO  (coreCloseExecutor-9797-thread-1) 
[n:127.0.0.1:62256_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.m.SolrMetricManager Closing metric 
reporters for: solr.core.authCollection.shard2.replica1
   [junit4]   2> 2603066 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.c.Overseer Overseer 
(id=97259905563885575-127.0.0.1:62256_solr-n_0000000000) closing
   [junit4]   2> 2603066 INFO  
(OverseerStateUpdate-97259905563885575-127.0.0.1:62256_solr-n_0000000000) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:62256_solr
   [junit4]   2> 2603069 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x159895e6a060007, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2603069 INFO  (zkCallback-4319-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
   [junit4]   2> 2603070 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:54752_solr
   [junit4]   2> 2603071 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (2)
   [junit4]   2> 2603071 INFO  
(zkCallback-4313-thread-2-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (2)
   [junit4]   2> 2603072 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.Overseer Overseer 
(id=97259905563885577-127.0.0.1:54752_solr-n_0000000001) starting
   [junit4]   2> 2603086 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_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> 2603086 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_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> 2603195 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_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> 2603195 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_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> 2604570 WARN  
(zkCallback-4310-thread-1-processing-n:127.0.0.1:62256_solr) 
[n:127.0.0.1:62256_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2604570 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2604571 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6af6f7f2{/solr,null,UNAVAILABLE}
   [junit4]   2> 2604572 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2604574 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@533bd390{/solr,null,AVAILABLE}
   [junit4]   2> 2604574 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@11ddfe67{HTTP/1.1,[http/1.1]}{127.0.0.1:33253}
   [junit4]   2> 2604574 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.e.j.s.Server Started @2608685ms
   [junit4]   2> 2604574 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=33253}
   [junit4]   2> 2604574 ERROR 
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2604574 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 2604574 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2604575 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2604575 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-10T17:13:08.857Z
   [junit4]   2> 2604577 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2604584 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51250/solr
   [junit4]   2> 2604599 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 2604606 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:33253_solr
   [junit4]   2> 2604609 INFO  (zkCallback-4319-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2604609 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2604609 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2604610 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 2604684 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2604684 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2604684 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is 
managing internode requests
   [junit4]   2> 2604687 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CorePropertiesLocator Found 1 core 
definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node1/.
   [junit4]   2> 2604687 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CorePropertiesLocator Cores are: 
[authCollection_shard2_replica1]
   [junit4]   2> 2604793 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_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> 2604793 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_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> 2604793 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_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> 2605709 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 
7.0.0
   [junit4]   2> 2605716 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.s.IndexSchema 
[authCollection_shard2_replica1] Schema name=minimal
   [junit4]   2> 2605717 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 
with uniqueid field id
   [junit4]   2> 2605718 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.CoreContainer Creating SolrCore 
'authCollection_shard2_replica1' using configuration from collection 
authCollection
   [junit4]   2> 2605719 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.SolrCore 
[[authCollection_shard2_replica1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node1/authCollection_shard2_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node1/./authCollection_shard2_replica1/data/]
   [junit4]   2> 2605728 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2605728 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: 
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 2605729 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.u.CommitTracker Hard AutoCommit: 
disabled
   [junit4]   2> 2605729 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.u.CommitTracker Soft AutoCommit: 
disabled
   [junit4]   2> 2605729 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7bc79e28[authCollection_shard2_replica1] main]
   [junit4]   2> 2605731 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.r.ManagedResourceStorage Configured 
ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2605731 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.r.ManagedResourceStorage Loaded null at 
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2605731 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.h.ReplicationHandler Commits will be 
reserved for  10000
   [junit4]   2> 2605731 INFO  
(coreLoadExecutor-9806-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.u.UpdateLog Could not find max version 
in index or recent updates, using new clock 1556158496126271488
   [junit4]   2> 2605731 INFO  
(searcherExecutor-9807-thread-1-processing-n:127.0.0.1:33253_solr 
x:authCollection_shard2_replica1 s:shard2 c:authCollection r:core_node2) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.SolrCore 
[authCollection_shard2_replica1] Registered new searcher 
Searcher@7bc79e28[authCollection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2605738 INFO  
(coreZkRegister-9801-thread-1-processing-n:127.0.0.1:33253_solr 
x:authCollection_shard2_replica1 s:shard2 c:authCollection r:core_node2) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.ShardLeaderElectionContext Enough 
replicas found to continue.
   [junit4]   2> 2605738 INFO  
(coreZkRegister-9801-thread-1-processing-n:127.0.0.1:33253_solr 
x:authCollection_shard2_replica1 s:shard2 c:authCollection r:core_node2) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I may be 
the new leader - try and sync
   [junit4]   2> 2605739 INFO  
(coreZkRegister-9801-thread-1-processing-n:127.0.0.1:33253_solr 
x:authCollection_shard2_replica1 s:shard2 c:authCollection r:core_node2) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:33253/solr/authCollection_shard2_replica1/
   [junit4]   2> 2605739 INFO  
(coreZkRegister-9801-thread-1-processing-n:127.0.0.1:33253_solr 
x:authCollection_shard2_replica1 s:shard2 c:authCollection r:core_node2) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.SyncStrategy Sync Success - now sync 
replicas to me
   [junit4]   2> 2605739 INFO  
(coreZkRegister-9801-thread-1-processing-n:127.0.0.1:33253_solr 
x:authCollection_shard2_replica1 s:shard2 c:authCollection r:core_node2) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.SyncStrategy 
http://127.0.0.1:33253/solr/authCollection_shard2_replica1/ has no replicas
   [junit4]   2> 2605743 INFO  
(coreZkRegister-9801-thread-1-processing-n:127.0.0.1:33253_solr 
x:authCollection_shard2_replica1 s:shard2 c:authCollection r:core_node2) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I am the 
new leader: http://127.0.0.1:33253/solr/authCollection_shard2_replica1/ shard2
   [junit4]   2> 2605744 INFO  
(coreZkRegister-9801-thread-1-processing-n:127.0.0.1:33253_solr 
x:authCollection_shard2_replica1 s:shard2 c:authCollection r:core_node2) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.ZkController I am the leader, no 
recovery necessary
   [junit4]   2> 2605820 INFO  (qtp1663791355-25027) [n:127.0.0.1:33253_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} 
status=0 QTime=0
   [junit4]   2> 2605824 INFO  (qtp1000205469-24934) [n:127.0.0.1:55792_solr    
] o.a.s.s.RuleBasedAuthorizationPlugin request has come without principal. 
failed permission {
   [junit4]   2>   "name":"security-edit",
   [junit4]   2>   "role":"admin"} 
   [junit4]   2> 2605824 INFO  (qtp1000205469-24934) [n:127.0.0.1:55792_solr    
] o.a.s.s.HttpSolrCall USER_REQUIRED auth header null context : userPrincipal: 
[null] type: [ADMIN], collections: [], Path: [/admin/authentication] path : 
/admin/authentication params :wt=javabin&version=2 
   [junit4]   2> 2605826 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.s.BasicAuthIntegrationTest Added Basic Auth security Header 
c29scjpTb2xyUm9ja3M=
   [junit4]   2> 2605826 INFO  (qtp1663791355-25028) [n:127.0.0.1:33253_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} 
status=0 QTime=0
   [junit4]   2> 2605829 INFO  (qtp1663791355-25026) [n:127.0.0.1:33253_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} 
status=0 QTime=1
   [junit4]   2> 2605830 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605830 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605830 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605830 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605830 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605830 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605831 INFO  (qtp604281198-24947) [n:127.0.0.1:54752_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} 
status=0 QTime=0
   [junit4]   2> 2605832 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.s.BasicAuthIntegrationTest Added Basic Auth security Header 
c29scjpTb2xyUm9ja3M=
   [junit4]   2> 2605834 INFO  (qtp604281198-24931) [n:127.0.0.1:54752_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} 
status=0 QTime=2
   [junit4]   2> 2605835 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605835 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2605835 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605836 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605836 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605836 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2605836 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2605836 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605836 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605837 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} 
status=0 QTime=0
   [junit4]   2> 2605838 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.s.BasicAuthIntegrationTest Added Basic Auth security Header 
aGFycnk6SGFycnlJc1ViZXJDb29s
   [junit4]   2> 2605841 INFO  (qtp604281198-24943) [n:127.0.0.1:54752_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} 
status=0 QTime=2
   [junit4]   2> 2605841 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605841 INFO  (qtp604281198-24941) [n:127.0.0.1:54752_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} 
status=0 QTime=0
   [junit4]   2> 2605841 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2605842 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605842 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605842 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2605842 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605842 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605842 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2605842 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605850 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_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> 2605850 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_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> 2605850 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_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> 2605893 INFO  (qtp604281198-24945) [n:127.0.0.1:54752_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} 
status=0 QTime=0
   [junit4]   2> 2605894 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.s.BasicAuthIntegrationTest Added Basic Auth security Header 
aGFycnk6SGFycnlJc1ViZXJDb29s
   [junit4]   2> 2605896 INFO  (qtp604281198-24946) [n:127.0.0.1:54752_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} 
status=0 QTime=1
   [junit4]   2> 2605897 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605897 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2605897 INFO  (qtp604281198-24947) [n:127.0.0.1:54752_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} 
status=0 QTime=0
   [junit4]   2> 2605897 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605897 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2605897 INFO  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605900 INFO  (qtp604281198-24947) [n:127.0.0.1:54752_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params 
name=authCollection&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2605901 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Security node changed, 
reloading security.json
   [junit4]   2> 2605901 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Initializing authorization 
plugin: solr.RuleBasedAuthorizationPlugin
   [junit4]   2> 2605901 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605903 INFO  
(OverseerThreadFactory-9800-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.OverseerCollectionMessageHandler Executing 
Collection Cmd : action=RELOAD
   [junit4]   2> 2605936 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key 
params={omitHeader=true&wt=json} status=0 QTime=0
   [junit4]   2> 2605936 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr    
] o.a.s.s.PKIAuthenticationPlugin New Key obtained from  node: 
127.0.0.1:33253_solr / 
MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDbUv42eDWFOgv8oay7mTj31XmRzIUBKx9ZaexjdNHhU9py81gswcYLeUTLFkV7ZuYBjg5o5s1bV8tPreOdR0bwrHVWPRBKMRwTIrQYh9lsonvK0fLNlPdvKUKKQqjLOkL4wvc2uqLcrCml3HfLsf9pceJIsI21DODnMDISeJ+3rwIDAQAB
   [junit4]   2> 2605948 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr    
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2605952 INFO  
(zkCallback-4313-thread-1-processing-n:127.0.0.1:54752_solr) 
[n:127.0.0.1:54752_solr    ] o.a.s.c.CoreContainer Initializing authentication 
plugin: solr.BasicAuthPlugin
   [junit4]   2> 2605954 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key 
params={omitHeader=true&wt=json} status=0 QTime=0
   [junit4]   2> 2605954 INFO  (qtp1000205469-24935) [    ] 
o.a.s.s.PKIAuthenticationPlugin New Key obtained from  node: 
127.0.0.1:33253_solr / 
MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDbUv42eDWFOgv8oay7mTj31XmRzIUBKx9ZaexjdNHhU9py81gswcYLeUTLFkV7ZuYBjg5o5s1bV8tPreOdR0bwrHVWPRBKMRwTIrQYh9lsonvK0fLNlPdvKUKKQqjLOkL4wvc2uqLcrCml3HfLsf9pceJIsI21DODnMDISeJ+3rwIDAQAB
   [junit4]   2> 2605955 INFO  (qtp1663791355-25030) [n:127.0.0.1:33253_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key 
params={omitHeader=true&wt=json} status=0 QTime=0
   [junit4]   2> 2605956 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr    
] o.a.s.s.PKIAuthenticationPlugin New Key obtained from  node: 
127.0.0.1:33253_solr / 
MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDbUv42eDWFOgv8oay7mTj31XmRzIUBKx9ZaexjdNHhU9py81gswcYLeUTLFkV7ZuYBjg5o5s1bV8tPreOdR0bwrHVWPRBKMRwTIrQYh9lsonvK0fLNlPdvKUKKQqjLOkL4wvc2uqLcrCml3HfLsf9pceJIsI21DODnMDISeJ+3rwIDAQAB
   [junit4]   2> 2605965 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr    
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2605965 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr    
] o.a.s.s.IndexSchema [authCollection_shard3_replica1] Schema name=minimal
   [junit4]   2> 2605968 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr    
] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 2605968 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr    
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2605969 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr    
] o.a.s.c.CoreContainer Reloading SolrCore 'authCollection_shard3_replica1' 
using configuration from collection authCollection
   [junit4]   2> 2605970 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.c.SolrCore [[authCollection_shard3_replica1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node3/authCollection_shard3_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node3/./authCollection_shard3_replica1/data/]
   [junit4]   2> 2605976 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr    
] o.a.s.s.IndexSchema [authCollection_shard1_replica1] Schema name=minimal
   [junit4]   2> 2605993 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr    
] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 2605998 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2605998 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2605999 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@a335a4[authCollection_shard3_replica1] main]
   [junit4]   2> 2606000 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2606001 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2606001 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2606008 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1556158496416727040
   [junit4]   2> 2606008 INFO  
(searcherExecutor-9812-thread-1-processing-n:127.0.0.1:54752_solr 
x:authCollection_shard3_replica1 s:shard3 c:authCollection r:core_node1) 
[n:127.0.0.1:54752_solr c:authCollection s:shard3 r:core_node1 
x:authCollection_shard3_replica1] o.a.s.c.SolrCore 
[authCollection_shard3_replica1] Registered new searcher 
Searcher@a335a4[authCollection_shard3_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2606008 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr    
] o.a.s.c.CoreContainer Reloading SolrCore 'authCollection_shard1_replica1' 
using configuration from collection authCollection
   [junit4]   2> 2606009 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.c.SolrCore [[authCollection_shard1_replica1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node2/authCollection_shard1_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node2/./authCollection_shard1_replica1/data/]
   [junit4]   2> 2606012 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr    
] o.a.s.s.IndexSchema [authCollection_shard2_replica1] Schema name=minimal
   [junit4]   2> 2606013 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr    
] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 2606014 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr    
] o.a.s.c.CoreContainer Reloading SolrCore 'authCollection_shard2_replica1' 
using configuration from collection authCollection
   [junit4]   2> 2606015 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.c.SolrCore [[authCollection_shard2_replica1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node1/authCollection_shard2_replica1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001/tempDir-001/node1/./authCollection_shard2_replica1/data/]
   [junit4]   2> 2606021 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2606021 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2606021 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7461f5d6[authCollection_shard1_replica1] main]
   [junit4]   2> 2606024 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 2606024 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2606024 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@373aad0b[authCollection_shard3_replica1] main]
   [junit4]   2> 2606025 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.c.SolrCore [authCollection_shard3_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@78554a2a
   [junit4]   2> 2606025 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2606025 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2606025 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.authCollection.shard3.replica1
   [junit4]   2> 2606026 INFO  
(searcherExecutor-9816-thread-1-processing-n:127.0.0.1:55792_solr 
x:authCollection_shard1_replica1 s:shard1 c:authCollection r:core_node3) 
[n:127.0.0.1:55792_solr c:authCollection s:shard1 r:core_node3 
x:authCollection_shard1_replica1] o.a.s.c.SolrCore 
[authCollection_shard1_replica1] Registered new searcher 
Searcher@7461f5d6[authCollection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2606026 INFO  (qtp604281198-24944) [n:127.0.0.1:54752_solr 
c:authCollection s:shard3 r:core_node1 x:authCollection_shard3_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={core=authCollection_shard3_replica1&qt=/admin/cores&action=RELOAD&wt=javabin&version=2}
 status=0 QTime=86
   [junit4]   2> 2606028 INFO  
(searcherExecutor-9812-thread-1-processing-n:127.0.0.1:54752_solr 
x:authCollection_shard3_replica1 s:shard3 c:authCollection r:core_node1) 
[n:127.0.0.1:54752_solr c:authCollection s:shard3 r:core_node1 
x:authCollection_shard3_replica1] o.a.s.c.SolrCore 
[authCollection_shard3_replica1] Registered new searcher 
Searcher@373aad0b[authCollection_shard3_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2606028 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1556158496437698560
   [junit4]   2> 2606035 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 2606035 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3617d220[authCollection_shard1_replica1] main]
   [junit4]   2> 2606035 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.c.SolrCore [authCollection_shard1_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1e8ed584
   [junit4]   2> 2606036 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.authCollection.shard1.replica1
   [junit4]   2> 2606036 INFO  (qtp1000205469-24935) [n:127.0.0.1:55792_solr 
c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={core=authCollection_shard1_replica1&qt=/admin/cores&action=RELOAD&wt=javabin&version=2}
 status=0 QTime=81
   [junit4]   2> 2606036 INFO  
(searcherExecutor-9816-thread-1-processing-n:127.0.0.1:55792_solr 
x:authCollection_shard1_replica1 s:shard1 c:authCollection r:core_node3) 
[n:127.0.0.1:55792_solr c:authCollection s:shard1 r:core_node3 
x:authCollection_shard1_replica1] o.a.s.c.SolrCore 
[authCollection_shard1_replica1] Registered new searcher 
Searcher@3617d220[authCollection_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2606046 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2606046 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2606046 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@39dca3ba[authCollection_shard2_replica1] main]
   [junit4]   2> 2606048 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2606048 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2606048 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2606058 INFO  
(searcherExecutor-9817-thread-1-processing-n:127.0.0.1:33253_solr 
x:authCollection_shard2_replica1 s:shard2 c:authCollection r:core_node2) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.SolrCore 
[authCollection_shard2_replica1] Registered new searcher 
Searcher@39dca3ba[authCollection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2606058 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1556158496469155840
   [junit4]   2> 2606062 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 2606062 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@73ffcbee[authCollection_shard2_replica1] main]
   [junit4]   2> 2606062 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.c.SolrCore [authCollection_shard2_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1a5a5eac
   [junit4]   2> 2606063 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.authCollection.shard2.replica1
   [junit4]   2> 2606063 INFO  (qtp1663791355-25029) [n:127.0.0.1:33253_solr 
c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={core=authCollection_shard2_replica1&qt=/admin/cores&action=RELOAD&wt=javabin&version=2}
 status=0 QTime=106
   [junit4]   2> 2606063 INFO  
(searcherExecutor-9817-thread-1-processing-n:127.0.0.1:33253_solr 
x:authCollection_shard2_replica1 s:shard2 c:authCollection r:core_node2) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.c.SolrCore 
[authCollection_shard2_replica1] Registered new searcher 
Searcher@73ffcbee[authCollection_shard2_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2606073 INFO  (qtp604281198-24947) [n:127.0.0.1:54752_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=authCollection&action=RELOAD&wt=javabin&version=2} status=0 
QTime=173
   [junit4]   2> 2606075 INFO  
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[476CF8A00ED578A9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testBasicAuth
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=BasicAuthIntegrationTest -Dtests.method=testBasicAuth 
-Dtests.seed=476CF8A00ED578A9 -Dtests.slow=true -Dtests.locale=es-CU 
-Dtests.timezone=America/Nome -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 3.11s J0 | BasicAuthIntegrationTest.testBasicAuth <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: must have failed
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([476CF8A00ED578A9:FB028EB2AA86FBD3]:0)
   [junit4]    >        at 
org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:159)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2606081 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@75276fb1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2606081 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@45d7da13{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2606081 INFO  (jetty-launcher-4296-thread-3) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=558498739
   [junit4]   2> 2606082 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1357009544
   [junit4]   2> 2606085 INFO  (coreCloseExecutor-9824-thread-1) 
[n:127.0.0.1:55792_solr c:authCollection s:shard1 r:core_node3 
x:authCollection_shard1_replica1] o.a.s.c.SolrCore 
[authCollection_shard1_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2db23a01
   [junit4]   2> 2606086 IN

[...truncated too long message...]

INFO  (zkCallback-4324-thread-2-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:33253_solr
   [junit4]   2> 2606100 INFO  
(zkCallback-4324-thread-1-processing-n:127.0.0.1:33253_solr) 
[n:127.0.0.1:33253_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 2606101 INFO  (coreCloseExecutor-9828-thread-1) 
[n:127.0.0.1:33253_solr c:authCollection s:shard2 r:core_node2 
x:authCollection_shard2_replica1] o.a.s.m.SolrMetricManager Closing metric 
reporters for: solr.core.authCollection.shard2.replica1
   [junit4]   2> 2607588 WARN  
(zkCallback-4312-thread-1-processing-n:127.0.0.1:55792_solr) 
[n:127.0.0.1:55792_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2607588 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2607589 INFO  (jetty-launcher-4296-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@490a6e4e{/solr,null,UNAVAILABLE}
   [junit4]   2> 2607605 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 2607606 INFO  (jetty-launcher-4296-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@533bd390{/solr,null,UNAVAILABLE}
   [junit4]   2> 2607608 INFO  
(SUITE-BasicAuthIntegrationTest-seed#[476CF8A00ED578A9]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:51250 51250
   [junit4]   2> 2607645 INFO  (Thread-6627) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:51250 51250
   [junit4]   2> 2607646 WARN  (Thread-6627) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        5       /solr/aliases.json
   [junit4]   2>        5       /solr/clusterprops.json
   [junit4]   2>        4       /solr/security.json
   [junit4]   2>        4       /solr/configs/conf
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        5       /solr/clusterstate.json
   [junit4]   2>        4       /solr/collections/authCollection/state.json
   [junit4]   2>        2       
/solr/overseer_elect/election/97259905563885577-127.0.0.1:54752_solr-n_0000000001
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        14      /solr/overseer/queue
   [junit4]   2>        6       /solr/overseer/collection-queue-work
   [junit4]   2>        5       /solr/collections
   [junit4]   2>        4       /solr/live_nodes
   [junit4]   2>        2       /solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> 2607646 INFO  
(SUITE-BasicAuthIntegrationTest-seed#[476CF8A00ED578A9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_476CF8A00ED578A9-001
   [junit4]   2> Jan 10, 2017 5:13:11 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=706, maxMBSortInHeap=7.867001114982571, 
sim=RandomSimilarity(queryNorm=false): {}, locale=es-CU, timezone=America/Nome
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_112 
(64-bit)/cpus=3,threads=1,free=200143400,total=498073600
   [junit4]   2> NOTE: All tests run in this JVM: [TestCloudDeleteByQuery, 
PingRequestHandlerTest, SynonymTokenizerTest, DocValuesNotIndexedTest, 
TestReloadAndDeleteDocs, SimpleMLTQParserTest, MBeansHandlerTest, TestMacros, 
TestConfigSets, TestReloadDeadlock, CoreAdminCreateDiscoverTest, SampleTest, 
TestSolrConfigHandlerCloud, HdfsDirectoryFactoryTest, TestClusterStateMutator, 
TestFastOutputStream, TestCollationField, 
ClassificationUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, 
HighlighterMaxOffsetTest, BlockJoinFacetDistribTest, 
TestDistribDocBasedVersion, QueryEqualityTest, DirectoryFactoryTest, 
BigEndianAscendingWordDeserializerTest, MigrateRouteKeyTest, 
ClusterStateUpdateTest, TestSolrJ, SOLR749Test, OverseerTest, TestLRUCache, 
TestRawResponseWriter, DistributedQueueTest, 
HdfsTlogReplayBufferedWhileIndexingTest, TestCryptoKeys, PeerSyncTest, 
DistribDocExpirationUpdateProcessorTest, TestInitParams, TestFilteredDocIdSet, 
ConnectionManagerTest, TestSolr4Spatial2, TestSolrCoreSnapshots, 
TestPartialUpdateDeduplication, XsltUpdateRequestHandlerTest, 
TolerantUpdateProcessorTest, MetricUtilsTest, CdcrReplicationDistributedZkTest, 
TestRandomDVFaceting, TestGeoJSONResponseWriter, TimeZoneUtilsTest, 
SuggesterFSTTest, ClassificationUpdateProcessorTest, PrimUtilsTest, 
CoreMergeIndexesAdminHandlerTest, TestImplicitCoreProperties, 
SolrGraphiteReporterTest, TestCharFilters, TestLazyCores, TestDocTermOrds, 
SolrXmlInZkTest, TestTolerantSearch, TestJavabinTupleStreamParser, 
SimpleFacetsTest, CustomCollectionTest, TestCSVLoader, 
CloudExitableDirectoryReaderTest, TestPerFieldSimilarityWithDefaultOverride, 
TestPhraseSuggestions, TestDynamicFieldCollectionResource, TestSQLHandler, 
TestPostingsSolrHighlighter, ShardSplitTest, FullHLLTest, 
ChaosMonkeyNothingIsSafeTest, ManagedSchemaRoundRobinCloudTest, 
DirectUpdateHandlerTest, TestExclusionRuleCollectionAccess, 
DirectSolrConnectionTest, BadCopyFieldTest, AddBlockUpdateTest, 
CheckHdfsIndexTest, SpatialRPTFieldTypeTest, TestScoreJoinQPNoScore, 
TestStressVersions, TestXmlQParser, OutOfBoxZkACLAndCredentialsProvidersTest, 
CdcrUpdateLogTest, TestSolrConfigHandler, TestCSVResponseWriter, 
DocValuesMultiTest, SolrMetricsIntegrationTest, 
ConcurrentDeleteAndCreateCollectionTest, DOMUtilTest, TestJmxIntegration, 
TestBM25SimilarityFactory, DeleteLastCustomShardedReplicaTest, 
TestIBSimilarityFactory, SyncSliceTest, TestSolrDeletionPolicy1, 
CollectionStateFormat2Test, TestLockTree, DistanceUnitsTest, 
SpatialHeatmapFacetsTest, TestStressCloudBlindAtomicUpdates, 
RequiredFieldsTest, DeleteReplicaTest, CdcrRequestHandlerTest, 
TestDefaultStatsCache, TestSchemalessBufferedUpdates, PluginInfoTest, 
TestXIncludeConfig, SolrCLIZkUtilsTest, TestStressLucene, 
TestRandomCollapseQParserPlugin, BasicAuthStandaloneTest, 
AlternateDirectoryTest, TestCustomDocTransformer, TestManagedSchema, 
SearchHandlerTest, RecoveryZkTest, CurrencyFieldXmlFileTest, HdfsDirectoryTest, 
NumericFieldsTest, HLLUtilTest, TestAnalyzeInfixSuggestions, 
SolrRequestParserTest, TestOverriddenPrefixQueryForCustomFieldType, 
TestStressReorder, TermsComponentTest, DistributedQueryElevationComponentTest, 
DocValuesMissingTest, LeaderElectionTest, TestSerializedLuceneMatchVersion, 
FastVectorHighlighterTest, TestAuthorizationFramework, 
DocumentAnalysisRequestHandlerTest, DistributedFacetExistsSmallTest, 
HdfsChaosMonkeySafeLeaderTest, ZkCLITest, ReplaceNodeTest, SolrPluginUtilsTest, 
TestLegacyFieldCache, SSLMigrationTest, HttpPartitionTest, 
TestJsonFacetRefinement, ZkControllerTest, TestCloudRecovery, 
TestDocBasedVersionConstraints, TestCollectionAPI, 
UniqFieldsUpdateProcessorFactoryTest, AssignTest, TestCustomStream, 
RuleEngineTest, TestRTimerTree, TestManagedSchemaThreadSafety, 
HdfsNNFailoverTest, TestLeaderElectionWithEmptyReplica, 
TestSolrCloudWithHadoopAuthPlugin, IndexSchemaRuntimeFieldTest, 
TestRequestStatusCollectionAPI, TestSort, TestSolrCloudWithKerberosAlt, 
HardAutoCommitTest, TestSolrXml, CachingDirectoryFactoryTest, 
TestComplexPhraseLeadingWildcard, HdfsWriteToMultipleCollectionsTest, 
DisMaxRequestHandlerTest, TestWriterPerf, PrimitiveFieldTypeTest, 
XmlUpdateRequestHandlerTest, DocumentBuilderTest, TermVectorComponentTest, 
MoreLikeThisHandlerTest, IndexSchemaTest, TestLFUCache, TestRTGBase, 
CursorPagingTest, TestHighlightDedupGrouping, ActionThrottleTest, 
BaseCdcrDistributedZkTest, CdcrReplicationHandlerTest, CollectionReloadTest, 
CollectionsAPIAsyncDistributedZkTest, DeleteShardTest, DistribCursorPagingTest, 
ForceLeaderTest, LeaderInitiatedRecoveryOnCommitTest, PeerSyncReplicationTest, 
SaslZkACLProviderTest, SharedFSAutoReplicaFailoverTest, 
SharedFSAutoReplicaFailoverUtilsTest, TestCloudPivotFacet, 
TestLeaderInitiatedRecoveryThread, TestOnReconnectListenerSupport, 
TestReplicaProperties, TestRequestForwarding, TestSegmentSorting, 
TestSizeLimitedDistributedMap, VMParamsZkACLAndCredentialsProvidersTest, 
HdfsSyncSliceTest, HdfsThreadLeakTest, HdfsUnloadDistributedZkTest, 
ZkStateReaderTest, ZkStateWriterTest, RulesTest, TestCorePropertiesReload, 
TestInfoStreamLogging, TestShardHandlerFactory, TestSolrDynamicMBean, 
TestSolrCloudSnapshots, RequestLoggingTest, TestBlobHandler, 
TestReplicationHandlerBackup, TestRestoreCore, DistributedFacetPivotLargeTest, 
DistributedFacetPivotSmallTest, DistributedQueryComponentCustomSortTest, 
DistributedSuggestComponentTest, TestExpandComponent, 
TestTrackingShardHandlerFactory, TestMacroExpander, TestBinaryResponseWriter, 
TestRawTransformer, TestSubQueryTransformer, TestManagedResource, 
TestManagedResourceStorage, TestBulkSchemaAPI, TestCopyFieldCollectionResource, 
TestDynamicFieldResource, TestFieldCollectionResource, TestFieldTypeResource, 
TestSchemaResource, TestSchemaVersionResource, TestUniqueKeyFieldResource, 
TestManagedSynonymFilterFactory, TestCloudSchemaless, TestSearcherReuse, 
TestSimpleQParserPlugin, TestSmileRequest, TestSolrFieldCacheMBean, 
TestStressUserVersions, TestMinMaxOnMultiValuedField, TestOrdValues, 
TestSortByMinMaxFunction, BJQParserTest, BlockJoinFacetRandomTest, 
BlockJoinFacetSimpleTest, GraphQueryTest, TestScoreJoinQPScore, 
TestJsonRequest, CloudMLTQParserTest, TestClassicSimilarityFactory, 
TestDFISimilarityFactory, TestNonDefinedSimilarityFactory, TestDistribIDF, 
BasicAuthIntegrationTest]
   [junit4] Completed [643/677 (1!)] on J0 in 8.96s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 62528 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to