Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6594/ Java: 64bit/jdk1.8.0_131 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.security.BasicAuthIntegrationTest Error Message: ObjectTracker found 1 object(s) that were not released!!! [MockDirectoryWrapper] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347) at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:92) at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:753) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:948) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:855) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:973) at org.apache.solr.core.CoreContainer.lambda$load$7(CoreContainer.java:605) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:748) Stack Trace: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [MockDirectoryWrapper] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347) at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:92) at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:753) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:948) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:855) at org.apache.solr.core.CoreContainer.create(CoreContainer.java:973) at org.apache.solr.core.CoreContainer.lambda$load$7(CoreContainer.java:605) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([AEFB9D2CE5FB207E]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNull(Assert.java:551) at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:303) at sun.reflect.GeneratedMethodAccessor98.invoke(Unknown Source) 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$7.evaluate(RandomizedRunner.java:870) 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:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13171 lines...] [junit4] Suite: org.apache.solr.security.BasicAuthIntegrationTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\init-core-data-001 [junit4] 2> 3266118 WARN (SUITE-BasicAuthIntegrationTest-seed#[AEFB9D2CE5FB207E]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=27 numCloses=27 [junit4] 2> 3266118 INFO (SUITE-BasicAuthIntegrationTest-seed#[AEFB9D2CE5FB207E]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields [junit4] 2> 3266122 INFO (SUITE-BasicAuthIntegrationTest-seed#[AEFB9D2CE5FB207E]-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> 3266124 INFO (SUITE-BasicAuthIntegrationTest-seed#[AEFB9D2CE5FB207E]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001 [junit4] 2> 3266124 INFO (SUITE-BasicAuthIntegrationTest-seed#[AEFB9D2CE5FB207E]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 3266125 INFO (Thread-5789) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3266125 INFO (Thread-5789) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 3266128 ERROR (Thread-5789) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 3266225 INFO (SUITE-BasicAuthIntegrationTest-seed#[AEFB9D2CE5FB207E]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:63661 [junit4] 2> 3266238 INFO (jetty-launcher-5032-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 3266239 INFO (jetty-launcher-5032-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@76a44f0f{/solr,null,AVAILABLE} [junit4] 2> 3266239 INFO (jetty-launcher-5032-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2efc975{HTTP/1.1,[http/1.1]}{127.0.0.1:63668} [junit4] 2> 3266239 INFO (jetty-launcher-5032-thread-1) [ ] o.e.j.s.Server Started @3270257ms [junit4] 2> 3266239 INFO (jetty-launcher-5032-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63668} [junit4] 2> 3266240 ERROR (jetty-launcher-5032-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3266240 INFO (jetty-launcher-5032-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 3266240 INFO (jetty-launcher-5032-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3266240 INFO (jetty-launcher-5032-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3266240 INFO (jetty-launcher-5032-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-27T05:23:24.927Z [junit4] 2> 3266243 INFO (jetty-launcher-5032-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 3266243 INFO (jetty-launcher-5032-thread-3) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 3266243 INFO (jetty-launcher-5032-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@615aa266{/solr,null,AVAILABLE} [junit4] 2> 3266245 INFO (jetty-launcher-5032-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b21e777{/solr,null,AVAILABLE} [junit4] 2> 3266245 INFO (jetty-launcher-5032-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@140eea33{HTTP/1.1,[http/1.1]}{127.0.0.1:63673} [junit4] 2> 3266245 INFO (jetty-launcher-5032-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@49e52c04{HTTP/1.1,[http/1.1]}{127.0.0.1:63674} [junit4] 2> 3266246 INFO (jetty-launcher-5032-thread-2) [ ] o.e.j.s.Server Started @3270263ms [junit4] 2> 3266246 INFO (jetty-launcher-5032-thread-3) [ ] o.e.j.s.Server Started @3270263ms [junit4] 2> 3266246 INFO (jetty-launcher-5032-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63673} [junit4] 2> 3266246 INFO (jetty-launcher-5032-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63674} [junit4] 2> 3266246 ERROR (jetty-launcher-5032-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3266246 ERROR (jetty-launcher-5032-thread-3) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3266246 INFO (jetty-launcher-5032-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 3266246 INFO (jetty-launcher-5032-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 3266246 INFO (jetty-launcher-5032-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3266246 INFO (jetty-launcher-5032-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3266246 INFO (jetty-launcher-5032-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3266247 INFO (jetty-launcher-5032-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-27T05:23:24.934Z [junit4] 2> 3266247 INFO (jetty-launcher-5032-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3266247 INFO (jetty-launcher-5032-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-27T05:23:24.934Z [junit4] 2> 3266258 INFO (jetty-launcher-5032-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3266259 INFO (jetty-launcher-5032-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3266263 INFO (jetty-launcher-5032-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3266322 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 0x15c485bf40f0002, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3266331 INFO (jetty-launcher-5032-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63661/solr [junit4] 2> 3266332 INFO (jetty-launcher-5032-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63661/solr [junit4] 2> 3266365 INFO (jetty-launcher-5032-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63661/solr [junit4] 2> 3266372 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 0x15c485bf40f0004, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3266375 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 0x15c485bf40f0006, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3266404 INFO (jetty-launcher-5032-thread-3) [n:127.0.0.1:63674_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3266404 INFO (jetty-launcher-5032-thread-2) [n:127.0.0.1:63673_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3266404 INFO (jetty-launcher-5032-thread-1) [n:127.0.0.1:63668_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3266405 INFO (jetty-launcher-5032-thread-3) [n:127.0.0.1:63674_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:63674_solr [junit4] 2> 3266406 INFO (jetty-launcher-5032-thread-1) [n:127.0.0.1:63668_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63668_solr [junit4] 2> 3266406 INFO (jetty-launcher-5032-thread-2) [n:127.0.0.1:63673_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63673_solr [junit4] 2> 3266407 INFO (jetty-launcher-5032-thread-3) [n:127.0.0.1:63674_solr ] o.a.s.c.Overseer Overseer (id=98032851669155848-127.0.0.1:63674_solr-n_0000000000) starting [junit4] 2> 3266408 INFO (zkCallback-5047-thread-1-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3266408 INFO (zkCallback-5049-thread-1-processing-n:127.0.0.1:63668_solr) [n:127.0.0.1:63668_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3266408 INFO (zkCallback-5048-thread-1-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3266448 INFO (jetty-launcher-5032-thread-3) [n:127.0.0.1:63674_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63674_solr [junit4] 2> 3266483 INFO (zkCallback-5047-thread-1-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3266483 INFO (zkCallback-5049-thread-1-processing-n:127.0.0.1:63668_solr) [n:127.0.0.1:63668_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3266485 INFO (zkCallback-5048-thread-1-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3266581 INFO (jetty-launcher-5032-thread-1) [n:127.0.0.1:63668_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63668.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3266582 INFO (jetty-launcher-5032-thread-3) [n:127.0.0.1:63674_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63674.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3266583 INFO (jetty-launcher-5032-thread-2) [n:127.0.0.1:63673_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63673.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3266585 INFO (jetty-launcher-5032-thread-3) [n:127.0.0.1:63674_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63674.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3266585 INFO (jetty-launcher-5032-thread-3) [n:127.0.0.1:63674_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63674.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3266585 INFO (jetty-launcher-5032-thread-1) [n:127.0.0.1:63668_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63668.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3266585 INFO (jetty-launcher-5032-thread-1) [n:127.0.0.1:63668_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63668.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3266585 INFO (jetty-launcher-5032-thread-2) [n:127.0.0.1:63673_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63673.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3266585 INFO (jetty-launcher-5032-thread-2) [n:127.0.0.1:63673_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63673.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3266586 INFO (jetty-launcher-5032-thread-3) [n:127.0.0.1:63674_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node3\. [junit4] 2> 3266586 INFO (jetty-launcher-5032-thread-1) [n:127.0.0.1:63668_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node1\. [junit4] 2> 3266586 INFO (jetty-launcher-5032-thread-2) [n:127.0.0.1:63673_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node2\. [junit4] 2> 3266733 INFO (SUITE-BasicAuthIntegrationTest-seed#[AEFB9D2CE5FB207E]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 3266735 INFO (SUITE-BasicAuthIntegrationTest-seed#[AEFB9D2CE5FB207E]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63661/solr ready [junit4] 2> 3266741 INFO (qtp1488110337-29039) [n:127.0.0.1:63668_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=1&collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 3266751 INFO (OverseerThreadFactory-11402-thread-1-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CreateCollectionCmd Create collection authCollection [junit4] 2> 3266858 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 3266858 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 3266862 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT [junit4] 2> 3266863 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3266863 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3266864 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3266971 INFO (zkCallback-5047-thread-1-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_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> 3266971 INFO (zkCallback-5048-thread-1-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_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> 3266971 INFO (zkCallback-5049-thread-1-processing-n:127.0.0.1:63668_solr) [n:127.0.0.1:63668_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> 3267897 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 3267898 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 3267908 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.s.IndexSchema [authCollection_shard3_replica_n1] Schema name=minimal [junit4] 2> 3267908 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.s.IndexSchema [authCollection_shard1_replica_n1] Schema name=minimal [junit4] 2> 3267913 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 3267940 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 3267940 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard1_replica_n1' using configuration from collection authCollection, trusted=true [junit4] 2> 3267941 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63674.solr.core.authCollection.shard1.replica_n1' (registry 'solr.core.authCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3267942 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 3267942 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore [[authCollection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node3\authCollection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node3\.\authCollection_shard1_replica_n1\data\] [junit4] 2> 3267942 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 3267943 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard3_replica_n1' using configuration from collection authCollection, trusted=true [junit4] 2> 3267943 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63673.solr.core.authCollection.shard3.replica_n1' (registry 'solr.core.authCollection.shard3.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3267943 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 3267944 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.SolrCore [[authCollection_shard3_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node2\authCollection_shard3_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node2\.\authCollection_shard3_replica_n1\data\] [junit4] 2> 3267947 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.s.IndexSchema [authCollection_shard2_replica_n1] Schema name=minimal [junit4] 2> 3267950 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 3267950 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard2_replica_n1' using configuration from collection authCollection, trusted=true [junit4] 2> 3267951 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63668.solr.core.authCollection.shard2.replica_n1' (registry 'solr.core.authCollection.shard2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3267951 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 3267951 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.SolrCore [[authCollection_shard2_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node1\authCollection_shard2_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node1\.\authCollection_shard2_replica_n1\data\] [junit4] 2> 3268115 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 3268115 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 3268115 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 3268115 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 3268116 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 3268116 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 3268119 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 3268119 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3268119 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 3268119 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3268121 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f8963db[authCollection_shard2_replica_n1] main] [junit4] 2> 3268121 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 3268122 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3268121 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@667ee5fb[authCollection_shard3_replica_n1] main] [junit4] 2> 3268123 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 3268123 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 3268124 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 3268124 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 3268124 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 3268124 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 3268125 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568525628800499712 [junit4] 2> 3268125 INFO (searcherExecutor-11408-thread-1-processing-n:127.0.0.1:63668_solr x:authCollection_shard2_replica_n1 s:shard2 c:authCollection) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.SolrCore [authCollection_shard2_replica_n1] Registered new searcher Searcher@1f8963db[authCollection_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3268125 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568525628800499712 [junit4] 2> 3268125 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@66a6b0d1[authCollection_shard1_replica_n1] main] [junit4] 2> 3268125 INFO (searcherExecutor-11407-thread-1-processing-n:127.0.0.1:63673_solr x:authCollection_shard3_replica_n1 s:shard3 c:authCollection) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.SolrCore [authCollection_shard3_replica_n1] Registered new searcher Searcher@667ee5fb[authCollection_shard3_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3268127 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 3268128 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 3268129 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 3268130 INFO (searcherExecutor-11406-thread-1-processing-n:127.0.0.1:63674_solr x:authCollection_shard1_replica_n1 s:shard1 c:authCollection) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore [authCollection_shard1_replica_n1] Registered new searcher Searcher@66a6b0d1[authCollection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3268130 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568525628805742592 [junit4] 2> 3268130 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 3268130 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 3268130 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 3268130 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:63668/solr/authCollection_shard2_replica_n1/ [junit4] 2> 3268135 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 3268135 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:63673/solr/authCollection_shard3_replica_n1/ [junit4] 2> 3268135 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 3268135 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:63673/solr/authCollection_shard3_replica_n1/ has no replicas [junit4] 2> 3268135 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 3268135 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 3268135 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:63668/solr/authCollection_shard2_replica_n1/ has no replicas [junit4] 2> 3268135 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 3268140 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 3268140 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 3268140 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:63673/solr/authCollection_shard3_replica_n1/ shard3 [junit4] 2> 3268140 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:63674/solr/authCollection_shard1_replica_n1/ [junit4] 2> 3268140 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 3268140 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:63674/solr/authCollection_shard1_replica_n1/ has no replicas [junit4] 2> 3268140 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 3268140 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:63668/solr/authCollection_shard2_replica_n1/ shard2 [junit4] 2> 3268143 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:63674/solr/authCollection_shard1_replica_n1/ shard1 [junit4] 2> 3268254 INFO (zkCallback-5047-thread-1-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_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> 3268255 INFO (zkCallback-5049-thread-1-processing-n:127.0.0.1:63668_solr) [n:127.0.0.1:63668_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> 3268255 INFO (zkCallback-5048-thread-1-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_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> 3268299 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 3268299 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 3268302 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 3268307 INFO (qtp1721207549-29057) [n:127.0.0.1:63673_solr c:authCollection s:shard3 x:authCollection_shard3_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1447 [junit4] 2> 3268307 INFO (qtp1488110337-29073) [n:127.0.0.1:63668_solr c:authCollection s:shard2 x:authCollection_shard2_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1453 [junit4] 2> 3268322 INFO (qtp2114361387-29060) [n:127.0.0.1:63674_solr c:authCollection s:shard1 x:authCollection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1465 [junit4] 2> 3268347 INFO (qtp1488110337-29039) [n:127.0.0.1:63668_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> 3268456 INFO (zkCallback-5047-thread-1-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_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> 3268457 INFO (zkCallback-5049-thread-1-processing-n:127.0.0.1:63668_solr) [n:127.0.0.1:63668_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> 3268457 INFO (zkCallback-5048-thread-1-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_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> 3268767 INFO (OverseerCollectionConfigSetProcessor-98032851669155848-127.0.0.1:63674_solr-n_0000000000) [n:127.0.0.1:63674_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> 3269348 INFO (qtp1488110337-29039) [n:127.0.0.1:63668_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&replicationFactor=1&collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=2606 [junit4] 2> 3269383 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBasicAuth [junit4] 2> 3269388 INFO (qtp1488110337-29046) [n:127.0.0.1:63668_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=0 [junit4] 2> 3269390 INFO (qtp1488110337-29046) [n:127.0.0.1:63668_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=0 [junit4] 2> 3269391 INFO (zkCallback-5047-thread-1-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3269391 INFO (zkCallback-5048-thread-1-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3269391 INFO (zkCallback-5047-thread-1-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3269391 INFO (zkCallback-5048-thread-1-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3269391 INFO (zkCallback-5049-thread-1-processing-n:127.0.0.1:63668_solr) [n:127.0.0.1:63668_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3269391 INFO (zkCallback-5049-thread-1-processing-n:127.0.0.1:63668_solr) [n:127.0.0.1:63668_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3269442 INFO (qtp1488110337-29046) [n:127.0.0.1:63668_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=0 [junit4] 2> 3269445 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2efc975{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 3269447 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2048527841 [junit4] 2> 3269448 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 3269450 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 3269452 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 3269453 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 3269455 INFO (coreCloseExecutor-11421-thread-1) [n:127.0.0.1:63668_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.SolrCore [authCollection_shard2_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@6a3a4f66 [junit4] 2> 3269459 INFO (coreCloseExecutor-11421-thread-1) [n:127.0.0.1:63668_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.authCollection.shard2.replica_n1, tag=1782206310 [junit4] 2> 3269462 INFO (coreCloseExecutor-11421-thread-1) [n:127.0.0.1:63668_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.authCollection.shard2.leader, tag=1782206310 [junit4] 2> 3269471 INFO (zkCallback-5056-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 3269523 INFO (zkCallback-5049-thread-1-processing-n:127.0.0.1:63668_solr) [n:127.0.0.1:63668_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3269525 INFO (zkCallback-5048-thread-1-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3269525 INFO (zkCallback-5047-thread-1-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3269565 INFO (zkCallback-5047-thread-3-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_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> 3269565 INFO (zkCallback-5048-thread-3-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_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> 3269614 INFO (zkCallback-5049-thread-1-processing-n:127.0.0.1:63668_solr) [n:127.0.0.1:63668_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests [junit4] 2> 3269614 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@76a44f0f{/solr,null,UNAVAILABLE} [junit4] 2> 3269614 INFO (zkCallback-5048-thread-1-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests [junit4] 2> 3269615 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 3269615 INFO (zkCallback-5047-thread-1-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests [junit4] 2> 3269616 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 3269616 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 3269616 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@51cd997{/solr,null,AVAILABLE} [junit4] 2> 3269618 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@294e4dee{HTTP/1.1,[http/1.1]}{127.0.0.1:63730} [junit4] 2> 3269619 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.e.j.s.Server Started @3273636ms [junit4] 2> 3269619 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63730} [junit4] 2> 3269619 ERROR (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 3269619 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 3269619 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 3269619 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 3269619 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-27T05:23:28.306Z [junit4] 2> 3269622 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 3269634 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63661/solr [junit4] 2> 3269658 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [n:127.0.0.1:63730_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 3269661 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [n:127.0.0.1:63730_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3269668 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [n:127.0.0.1:63730_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63730_solr [junit4] 2> 3269670 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3269670 INFO (zkCallback-5056-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3269670 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3269672 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 3269701 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3269702 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3269702 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests [junit4] 2> 3269837 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [n:127.0.0.1:63730_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63730.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3269842 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [n:127.0.0.1:63730_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63730.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3269842 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [n:127.0.0.1:63730_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63730.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3269850 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [n:127.0.0.1:63730_solr ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node1\. [junit4] 2> 3269850 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [n:127.0.0.1:63730_solr ] o.a.s.c.CorePropertiesLocator Cores are: [authCollection_shard2_replica_n1] [junit4] 2> 3269856 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 3269966 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_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> 3269966 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_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> 3269966 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_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> 3270879 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 3270887 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.s.IndexSchema [authCollection_shard2_replica_n1] Schema name=minimal [junit4] 2> 3270893 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 3270893 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard2_replica_n1' using configuration from collection authCollection, trusted=true [junit4] 2> 3270894 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63730.solr.core.authCollection.shard2.replica_n1' (registry 'solr.core.authCollection.shard2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3270894 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 3270894 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.SolrCore [[authCollection_shard2_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node1\authCollection_shard2_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node1\.\authCollection_shard2_replica_n1\data\] [junit4] 2> 3271071 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 3271071 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 3271072 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 3271072 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3271073 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1007fed1[authCollection_shard2_replica_n1] main] [junit4] 2> 3271082 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 3271086 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 3271087 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 3271088 INFO (searcherExecutor-11429-thread-1-processing-n:127.0.0.1:63730_solr x:authCollection_shard2_replica_n1 s:shard2 c:authCollection r:core_node2) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.SolrCore [authCollection_shard2_replica_n1] Registered new searcher Searcher@1007fed1[authCollection_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3271088 INFO (coreLoadExecutor-11428-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568525631907430400 [junit4] 2> 3271095 INFO (coreZkRegister-11423-thread-1-processing-n:127.0.0.1:63730_solr x:authCollection_shard2_replica_n1 s:shard2 c:authCollection r:core_node2) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 3271095 INFO (coreZkRegister-11423-thread-1-processing-n:127.0.0.1:63730_solr x:authCollection_shard2_replica_n1 s:shard2 c:authCollection r:core_node2) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 3271095 INFO (coreZkRegister-11423-thread-1-processing-n:127.0.0.1:63730_solr x:authCollection_shard2_replica_n1 s:shard2 c:authCollection r:core_node2) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:63730/solr/authCollection_shard2_replica_n1/ [junit4] 2> 3271095 INFO (coreZkRegister-11423-thread-1-processing-n:127.0.0.1:63730_solr x:authCollection_shard2_replica_n1 s:shard2 c:authCollection r:core_node2) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 3271095 INFO (coreZkRegister-11423-thread-1-processing-n:127.0.0.1:63730_solr x:authCollection_shard2_replica_n1 s:shard2 c:authCollection r:core_node2) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:63730/solr/authCollection_shard2_replica_n1/ has no replicas [junit4] 2> 3271095 INFO (coreZkRegister-11423-thread-1-processing-n:127.0.0.1:63730_solr x:authCollection_shard2_replica_n1 s:shard2 c:authCollection r:core_node2) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 3271098 INFO (coreZkRegister-11423-thread-1-processing-n:127.0.0.1:63730_solr x:authCollection_shard2_replica_n1 s:shard2 c:authCollection r:core_node2) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:63730/solr/authCollection_shard2_replica_n1/ shard2 [junit4] 2> 3271098 INFO (coreZkRegister-11423-thread-1-processing-n:127.0.0.1:63730_solr x:authCollection_shard2_replica_n1 s:shard2 c:authCollection r:core_node2) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 3271204 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_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> 3271204 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_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> 3271204 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_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> 3271422 INFO (qtp1895549040-29156) [n:127.0.0.1:63730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=4 [junit4] 2> 3271426 INFO (qtp2114361387-29062) [n:127.0.0.1:63674_solr ] o.a.s.s.RuleBasedAuthorizationPlugin request has come without principal. failed permission { [junit4] 2> "name":"security-edit", [junit4] 2> "role":"admin"} [junit4] 2> 3271427 INFO (qtp2114361387-29062) [n:127.0.0.1:63674_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> 3271444 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.s.BasicAuthIntegrationTest Added Basic Auth security Header c29scjpTb2xyUm9ja3M= [junit4] 2> 3271445 INFO (qtp1895549040-29151) [n:127.0.0.1:63730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=0 [junit4] 2> 3271453 INFO (qtp1895549040-29151) [n:127.0.0.1:63730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=2 [junit4] 2> 3271455 INFO (qtp2114361387-29061) [n:127.0.0.1:63674_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=0 [junit4] 2> 3271457 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271458 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271465 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271465 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271465 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271465 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271511 INFO (qtp2114361387-29061) [n:127.0.0.1:63674_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=0 [junit4] 2> 3271511 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.s.BasicAuthIntegrationTest Added Basic Auth security Header c29scjpTb2xyUm9ja3M= [junit4] 2> 3271534 INFO (qtp2114361387-29065) [n:127.0.0.1:63674_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} status=0 QTime=23 [junit4] 2> 3271534 INFO (qtp1721207549-29054) [n:127.0.0.1:63673_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} status=0 QTime=0 [junit4] 2> 3271539 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271539 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3271539 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271539 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271539 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3271539 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271562 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271562 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3271562 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271611 INFO (qtp1721207549-29054) [n:127.0.0.1:63673_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} status=0 QTime=0 [junit4] 2> 3271615 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.s.BasicAuthIntegrationTest Added Basic Auth security Header aGFycnk6SGFycnlJc1ViZXJDb29s [junit4] 2> 3271625 INFO (qtp1721207549-29052) [n:127.0.0.1:63673_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} status=0 QTime=5 [junit4] 2> 3271625 INFO (qtp1721207549-29051) [n:127.0.0.1:63673_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} status=0 QTime=0 [junit4] 2> 3271626 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271626 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271626 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3271626 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271627 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3271627 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271627 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271627 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3271627 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271679 INFO (qtp1721207549-29051) [n:127.0.0.1:63673_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} status=0 QTime=0 [junit4] 2> 3271679 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[AEFB9D2CE5FB207E]) [ ] o.a.s.s.BasicAuthIntegrationTest Added Basic Auth security Header aGFycnk6SGFycnlJc1ViZXJDb29s [junit4] 2> 3271689 INFO (qtp1721207549-29051) [n:127.0.0.1:63673_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} status=0 QTime=10 [junit4] 2> 3271693 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} status=0 QTime=0 [junit4] 2> 3271701 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271701 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3271701 INFO (zkCallback-5048-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271701 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271701 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3271701 INFO (zkCallback-5061-thread-1-processing-n:127.0.0.1:63730_solr) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271710 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 3271710 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 3271710 INFO (zkCallback-5047-thread-2-processing-n:127.0.0.1:63673_solr) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 3271751 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authorization params={} status=0 QTime=0 [junit4] 2> 3271761 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr ] o.a.s.s.RuleBasedAuthorizationPlugin request has come without principal. failed permission { [junit4] 2> "name":"collection-admin-edit", [junit4] 2> "role":"admin", [junit4] 2> "index":3} [junit4] 2> 3271761 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr ] o.a.s.s.HttpSolrCall USER_REQUIRED auth header null context : userPrincipal: [null] type: [ADMIN], collections: [authCollection,], Path: [/admin/collections] path : /admin/collections params :name=authCollection&action=RELOAD&wt=javabin&version=2 [junit4] 2> 3271766 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr ] o.a.s.s.RuleBasedAuthorizationPlugin request has come without principal. failed permission { [junit4] 2> "name":"collection-admin-edit", [junit4] 2> "role":"admin", [junit4] 2> "index":3} [junit4] 2> 3271766 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr ] o.a.s.s.HttpSolrCall USER_REQUIRED auth header null context : userPrincipal: [null] type: [ADMIN], collections: [], Path: [/admin/collections] path : /admin/collections params :name=authCollection&action=RELOAD&wt=javabin&version=2 [junit4] 2> 3271767 INFO (qtp2114361387-29076) [n:127.0.0.1:63674_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> 3271773 INFO (OverseerThreadFactory-11402-thread-2-processing-n:127.0.0.1:63674_solr) [n:127.0.0.1:63674_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=RELOAD [junit4] 2> 3271806 INFO (qtp1895549040-29155) [n:127.0.0.1:63730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key params={omitHeader=true&wt=json} status=0 QTime=0 [junit4] 2> 3271806 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr ] o.a.s.s.PKIAuthenticationPlugin New Key obtained from node: 127.0.0.1:63730_solr / MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDEtB11pcxQzcgpxcx+1qvVO4rl+xc9cubvSR1iqhIF9nsGY28S5rFVikVqjge9yPfa5Uevrrf9m6iD86JzurrmC7y1wP9hvbc06SULGn9FE+LhktL/6v3/h+afhLfYlWpuEHMvYuQtYWotZDivBs2DKEIPrIqMnjT6zTrwYEKHyQIDAQAB [junit4] 2> 3271808 INFO (qtp1895549040-29155) [n:127.0.0.1:63730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key params={omitHeader=true&wt=json} status=0 QTime=0 [junit4] 2> 3271808 INFO (qtp1895549040-29154) [ ] o.a.s.s.PKIAuthenticationPlugin New Key obtained from node: 127.0.0.1:63730_solr / MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDEtB11pcxQzcgpxcx+1qvVO4rl+xc9cubvSR1iqhIF9nsGY28S5rFVikVqjge9yPfa5Uevrrf9m6iD86JzurrmC7y1wP9hvbc06SULGn9FE+LhktL/6v3/h+afhLfYlWpuEHMvYuQtYWotZDivBs2DKEIPrIqMnjT6zTrwYEKHyQIDAQAB [junit4] 2> 3271811 INFO (qtp1895549040-29155) [n:127.0.0.1:63730_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key params={omitHeader=true&wt=json} status=0 QTime=0 [junit4] 2> 3271811 INFO (qtp2114361387-29077) [ ] o.a.s.s.PKIAuthenticationPlugin New Key obtained from node: 127.0.0.1:63730_solr / MIGfMA0GCSqGSIb3DQEBAQUAA4GNADCBiQKBgQDEtB11pcxQzcgpxcx+1qvVO4rl+xc9cubvSR1iqhIF9nsGY28S5rFVikVqjge9yPfa5Uevrrf9m6iD86JzurrmC7y1wP9hvbc06SULGn9FE+LhktL/6v3/h+afhLfYlWpuEHMvYuQtYWotZDivBs2DKEIPrIqMnjT6zTrwYEKHyQIDAQAB [junit4] 2> 3271842 INFO (qtp2114361387-29077) [n:127.0.0.1:63674_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 3271842 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 3271854 INFO (qtp2114361387-29077) [n:127.0.0.1:63674_solr ] o.a.s.s.IndexSchema [authCollection_shard1_replica_n1] Schema name=minimal [junit4] 2> 3271854 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr ] o.a.s.s.IndexSchema [authCollection_shard2_replica_n1] Schema name=minimal [junit4] 2> 3271862 INFO (qtp2114361387-29077) [n:127.0.0.1:63674_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 3271863 INFO (qtp2114361387-29077) [n:127.0.0.1:63674_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'authCollection_shard1_replica_n1' using configuration from collection authCollection [junit4] 2> 3271863 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 3271867 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'authCollection_shard2_replica_n1' using configuration from collection authCollection [junit4] 2> 3271874 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 3271886 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr ] o.a.s.s.IndexSchema [authCollection_shard3_replica_n1] Schema name=minimal [junit4] 2> 3271889 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 3271889 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'authCollection_shard3_replica_n1' using configuration from collection authCollection [junit4] 2> 3271899 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63730.solr.core.authCollection.shard2.replica_n1' (registry 'solr.core.authCollection.shard2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3271899 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.c.SolrCore [[authCollection_shard2_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node1\authCollection_shard2_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node1\.\authCollection_shard2_replica_n1\data\] [junit4] 2> 3271900 INFO (qtp2114361387-29077) [n:127.0.0.1:63674_solr c:authCollection s:shard1 r:core_node1 x:authCollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63674.solr.core.authCollection.shard1.replica_n1' (registry 'solr.core.authCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3271900 INFO (qtp2114361387-29077) [n:127.0.0.1:63674_solr c:authCollection s:shard1 r:core_node1 x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore [[authCollection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node3\authCollection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node3\.\authCollection_shard1_replica_n1\data\] [junit4] 2> 3271918 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr c:authCollection s:shard3 r:core_node3 x:authCollection_shard3_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63673.solr.core.authCollection.shard3.replica_n1' (registry 'solr.core.authCollection.shard3.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3743baf3 [junit4] 2> 3271919 INFO (qtp1721207549-29080) [n:127.0.0.1:63673_solr c:authCollection s:shard3 r:core_node3 x:authCollection_shard3_replica_n1] o.a.s.c.SolrCore [[authCollection_shard3_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node2\authCollection_shard3_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthIntegrationTest_AEFB9D2CE5FB207E-001\tempDir-001\node2\.\authCollection_shard3_replica_n1\data\] [junit4] 2> 3272095 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 3272095 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3272097 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4f6bdd3c[authCollection_shard2_replica_n1] main] [junit4] 2> 3272110 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 3272121 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 3272122 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 3272135 INFO (qtp1895549040-29154) [n:127.0.0.1:63730_solr c:authCollection s:shard2 r:core_node2 x:authCollection_shard2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568525633005289472 [junit [...truncated too long message...] tStressLucene, TestHttpShardHandlerFactory, IndexSchemaRuntimeFieldTest, TestFunctionQuery, ConnectionManagerTest, SolrCoreTest, TestSolrCloudSnapshots, ShardRoutingTest, ResourceLoaderTest, TestOverriddenPrefixQueryForCustomFieldType, BlockJoinFacetDistribTest, MergeStrategyTest, EnumFieldTest, VersionInfoTest, PropertiesRequestHandlerTest, PeerSyncTest, TestHighlightDedupGrouping, CollectionStateFormat2Test, FileUtilsTest, TestSolrDeletionPolicy1, TestStressVersions, HdfsChaosMonkeySafeLeaderTest, TestGroupingSearch, TestUpdate, TestDynamicLoading, StandardRequestHandlerTest, TestBinaryField, PeerSyncWithIndexFingerprintCachingTest, TestLRUStatsCache, TestSubQueryTransformerCrossCore, SuggestComponentContextFilterQueryTest, TestReRankQParserPlugin, TestPullReplicaErrorHandling, MoveReplicaTest, TestCryptoKeys, SolrCloudExampleTest, DistribDocExpirationUpdateProcessorTest, SimpleMLTQParserTest, DocValuesTest, TestFieldCacheVsDocValues, TestGraphTermsQParserPlugin, MultiTermTest, TestRemoteStreaming, TestPostingsSolrHighlighter, MigrateRouteKeyTest, TestLockTree, TestEmbeddedSolrServerConstructors, DocValuesNotIndexedTest, DistributedMLTComponentTest, RAMDirectoryFactoryTest, TestRTGBase, TestExclusionRuleCollectionAccess, TestBM25SimilarityFactory, TestMaxScoreQueryParser, TestSurroundQueryParser, OverseerModifyCollectionTest, TestSchemaNameResource, TestLazyCores, TestSolrCloudWithKerberosAlt, NumericFieldsTest, TestStressReorder, TestAuthorizationFramework, RecoveryZkTest, MBeansHandlerTest, TestPushWriter, ExitableDirectoryReaderTest, TestSQLHandlerNonCloud, TestUseDocValuesAsStored, HdfsWriteToMultipleCollectionsTest, TestMacroExpander, TestLFUCache, DistributedSuggestComponentTest, TestPerFieldSimilarity, SolrGraphiteReporterTest, RollingRestartTest, SimpleFacetsTest, HighlighterTest, TestHdfsUpdateLog, DocExpirationUpdateProcessorFactoryTest, TestCollapseQParserPlugin, QueryElevationComponentTest, CoreSorterTest, TestScoreJoinQPNoScore, TestRawTransformer, TestSolrConfigHandlerConcurrent, TestFastOutputStream, TestCloudPivotFacet, DateMathParserTest, TestFieldTypeCollectionResource, SolrTestCaseJ4Test, TestCollectionAPI, TestElisionMultitermQuery, ClassificationUpdateProcessorTest, BlockCacheTest, TestCorePropertiesReload, ClassificationUpdateProcessorFactoryTest, TestIBSimilarityFactory, DistributedSpellCheckComponentTest, SpatialFilterTest, ParsingFieldUpdateProcessorsTest, OutputWriterTest, HdfsTlogReplayBufferedWhileIndexingTest, PolyFieldTest, TestRestoreCore, TestSlowCompositeReaderWrapper, TestLeaderInitiatedRecoveryThread, AnalyticsMergeStrategyTest, SchemaVersionSpecificBehaviorTest, JSONWriterTest, AddBlockUpdateTest, UnloadDistributedZkTest, SolrShardReporterTest, BaseCdcrDistributedZkTest, SolrRequestParserTest, DocumentBuilderTest, TestChildDocTransformer, TestBinaryResponseWriter, TestAuthenticationFramework, SortSpecParsingTest, QueryResultKeyTest, TestManagedResource, TestRandomDVFaceting, TestWordDelimiterFilterFactory, TestCollationFieldDocValues, TestRandomRequestDistribution, TestSolrConfigHandler, HardAutoCommitTest, TestManagedSchema, TestFreeTextSuggestions, CdcrReplicationDistributedZkTest, LeaderFailoverAfterPartitionTest, TestSchemaManager, SimplePostToolTest, DirectSolrSpellCheckerTest, TestLegacyFieldCache, TestManagedSynonymGraphFilterFactory, DateFieldTest, OverriddenZkACLAndCredentialsProvidersTest, RequestLoggingTest, QueryEqualityTest, DistributedFacetExistsSmallTest, TestMultiWordSynonyms, SearchHandlerTest, TestInfoStreamLogging, TestLeaderElectionWithEmptyReplica, TestClassNameShortening, SoftAutoCommitTest, TestFaceting, TestExtendedDismaxParser, TestMinMaxOnMultiValuedField, MoreLikeThisHandlerTest, ExplicitHLLTest, DirectSolrConnectionTest, TestStressUserVersions, TestCloudNestedDocsSort, TestTrackingShardHandlerFactory, BlockJoinFacetSimpleTest, CollectionsAPIAsyncDistributedZkTest, HdfsNNFailoverTest, ActionThrottleTest, CursorPagingTest, TestJsonFacetRefinement, OverseerTest, TestComplexPhraseQParserPlugin, HdfsRecoveryZkTest, TestDistributedStatsComponentCardinality, TestReplicationHandlerBackup, TestDelegationWithHadoopAuth, TestSortByMinMaxFunction, TestCSVResponseWriter, TestPseudoReturnFields, TestBulkSchemaConcurrent, ShufflingReplicaListTransformerTest, TestDistribDocBasedVersion, SegmentsInfoRequestHandlerTest, BasicDistributedZkTest, SyncSliceTest, ClusterStateUpdateTest, LeaderElectionTest, ZkSolrClientTest, ZkCLITest, ShardRoutingCustomTest, TestDistributedGrouping, TestRecovery, TestHashPartitioner, ZkControllerTest, TestJoin, TestCoreContainer, StatsComponentTest, TestIndexSearcher, DistributedQueryElevationComponentTest, SpellCheckCollatorTest, TestFoldingMultitermQuery, SuggesterTest, TestCSVLoader, DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, SolrInfoBeanTest, DisMaxRequestHandlerTest, TestQueryUtils, PrimitiveFieldTypeTest, TermsComponentTest, PathHierarchyTokenizerFactoryTest, FieldAnalysisRequestHandlerTest, LoggingHandlerTest, IndexSchemaTest, TestCollationField, UpdateRequestProcessorFactoryTest, BinaryUpdateRequestHandlerTest, PingRequestHandlerTest, TestMergePolicyConfig, TestLuceneMatchVersion, TestPhraseSuggestions, TestCodecSupport, OpenExchangeRatesOrgProviderTest, PluginInfoTest, TestSystemIdResolver, PrimUtilsTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest, TestSolrJ, TestLRUCache, TestUtils, ZkNodePropsTest, SliceStateTest, UUIDFieldTest, CircularListTest, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestSimpleTrackingShardHandler, TestTolerantSearch, TestJettySolrRunner, ConnectionReuseTest, AssignTest, AsyncCallRequestStatusResponseTest, CdcrRequestHandlerTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, CollectionsAPISolrJTest, DeleteInactiveReplicaTest, DeleteNodeTest, DeleteStatusTest, DistribJoinFromCollectionTest, ForceLeaderTest, LeaderInitiatedRecoveryOnCommitTest, ReplicationFactorTest, SaslZkACLProviderTest, SolrXmlInZkTest, TestCloudInspectUtil, TestCloudRecovery, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestDownShardTolerantSearch, TestHdfsCloudBackupRestore, TestLocalFSCloudBackupRestore, TestMiniSolrCloudClusterSSL, TestOnReconnectListenerSupport, TestRebalanceLeaders, TestRequestStatusCollectionAPI, TestShortCircuitedRequests, TestSolrCloudWithDelegationTokens, TestSolrCloudWithSecureImpersonation, TestStressCloudBlindAtomicUpdates, TestStressInPlaceUpdates, HdfsDirectoryFactoryTest, TestBackupRepositoryFactory, TestConfigSetProperties, TestCustomStream, TestReqParamsAPI, TestSolrConfigHandlerCloud, FacetPivotSmallTest, InfixSuggestersTest, ReplicaListTransformerTest, TestExpandComponent, TestPivotHelperCode, HighlighterMaxOffsetTest, TestUnifiedSolrHighlighter, WrapperMergePolicyFactoryTest, MetricsConfigTest, SolrMetricManagerTest, SolrMetricsIntegrationTest, SolrGangliaReporterTest, SolrSlf4jReporterTest, TestJavabinTupleStreamParser, TestSolrQueryResponse, TestRestManager, TestCopyFieldCollectionResource, TestDynamicFieldCollectionResource, TestFieldCollectionResource, TestFieldResource, TestSchemaResource, TestSchemaSimilarityResource, TestSerializedLuceneMatchVersion, TestManagedStopFilterFactory, TestManagedSynonymFilterFactory, PreAnalyzedFieldManagedSchemaCloudTest, TestCloudSchemaless, TestPayloadCheckQParserPlugin, TestQueryWrapperFilter, TestRecoveryHdfs, TestReloadDeadlock, TestSimpleQParserPlugin, TestSolr4Spatial2, TestSolrFieldCacheBean, TestStandardQParsers, TestXmlQParser, BlockJoinFacetRandomTest, GraphQueryTest, TestNestedDocsSort, TestJsonRequest, TestDFISimilarityFactory, TestPerFieldSimilarityWithDefaultOverride, TestDefaultStatsCache, BasicAuthIntegrationTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicAuthIntegrationTest -Dtests.seed=AEFB9D2CE5FB207E -Dtests.slow=true -Dtests.locale=ga-IE -Dtests.timezone=America/Detroit -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J0 | BasicAuthIntegrationTest (suite) <<< [junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [MockDirectoryWrapper] [junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper [junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) [junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347) [junit4] > at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:92) [junit4] > at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:753) [junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:948) [junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:855) [junit4] > at org.apache.solr.core.CoreContainer.create(CoreContainer.java:973) [junit4] > at org.apache.solr.core.CoreContainer.lambda$load$7(CoreContainer.java:605) [junit4] > at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:197) [junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([AEFB9D2CE5FB207E]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:303) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [690/715 (1!)] on J0 in 27.45s, 1 test, 1 failure <<< FAILURES! [...truncated 43461 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org