You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/08/06 17:11:31 UTC
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk1.8.0_102) - Build #
17482 - Unstable!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17482/
Java: 64bit/jdk1.8.0_102 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.common.cloud.TestCollectionStateWatchers.testWatchesWorkForStateFormat1
Error Message:
CollectionStateWatcher not notified of stateformat=1 collection creation
Stack Trace:
java.lang.AssertionError: CollectionStateWatcher not notified of stateformat=1 collection creation
at __randomizedtesting.SeedInfo.seed([AF9A59AF351593BA:C85D4541C1ADB71B]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.common.cloud.TestCollectionStateWatchers.testWatchesWorkForStateFormat1(TestCollectionStateWatchers.java:267)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 13136 lines...]
[junit4] Suite: org.apache.solr.common.cloud.TestCollectionStateWatchers
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/init-core-data-001
[junit4] 2> 37251 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-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> 37252 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 37252 INFO (Thread-46) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 37252 INFO (Thread-46) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 37352 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:34157
[junit4] 2> 37352 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37353 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37356 INFO (zkCallback-156-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@122cb9c5 name:ZooKeeperConnection Watcher:127.0.0.1:34157 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37356 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37356 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37356 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 37362 INFO (jetty-launcher-155-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 37363 INFO (jetty-launcher-155-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 37365 INFO (jetty-launcher-155-thread-3) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 37367 INFO (jetty-launcher-155-thread-4) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 37369 INFO (jetty-launcher-155-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@78bed7b2{/solr,null,AVAILABLE}
[junit4] 2> 37371 INFO (jetty-launcher-155-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@55185cc6{/solr,null,AVAILABLE}
[junit4] 2> 37372 INFO (jetty-launcher-155-thread-3) [ ] o.e.j.s.ServerConnector Started ServerConnector@1629a298{HTTP/1.1,[http/1.1]}{127.0.0.1:44560}
[junit4] 2> 37372 INFO (jetty-launcher-155-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@56ba513f{/solr,null,AVAILABLE}
[junit4] 2> 37372 INFO (jetty-launcher-155-thread-3) [ ] o.e.j.s.Server Started @39270ms
[junit4] 2> 37372 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44560}
[junit4] 2> 37372 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 37372 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node3'
[junit4] 2> 37372 INFO (jetty-launcher-155-thread-4) [ ] o.e.j.s.ServerConnector Started ServerConnector@30f25b9e{HTTP/1.1,[http/1.1]}{127.0.0.1:35446}
[junit4] 2> 37372 INFO (jetty-launcher-155-thread-4) [ ] o.e.j.s.Server Started @39271ms
[junit4] 2> 37373 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 37373 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35446}
[junit4] 2> 37373 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 37373 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node4'
[junit4] 2> 37373 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 37373 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 37373 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37373 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 37374 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37376 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37376 INFO (jetty-launcher-155-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@17d5e8e9{HTTP/1.1,[http/1.1]}{127.0.0.1:33483}
[junit4] 2> 37376 INFO (jetty-launcher-155-thread-2) [ ] o.e.j.s.Server Started @39275ms
[junit4] 2> 37376 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33483}
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7fa87c2e{/solr,null,AVAILABLE}
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node2'
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@d715e39{HTTP/1.1,[http/1.1]}{127.0.0.1:36438}
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-1) [ ] o.e.j.s.Server Started @39275ms
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36438}
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 37377 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1'
[junit4] 2> 37378 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 37378 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 37378 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37378 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37379 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37383 INFO (zkCallback-157-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5175f242 name:ZooKeeperConnection Watcher:127.0.0.1:34157/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37383 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37383 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37386 INFO (zkCallback-158-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4cf0544b name:ZooKeeperConnection Watcher:127.0.0.1:34157/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37386 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37386 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37386 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 37387 INFO (zkCallback-160-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5b03f0d5 name:ZooKeeperConnection Watcher:127.0.0.1:34157/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37387 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37387 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 37387 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37387 INFO (zkCallback-159-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c337899 name:ZooKeeperConnection Watcher:127.0.0.1:34157/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37387 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 37387 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37387 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37392 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 37397 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node4/.
[junit4] 2> 37398 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.CoreContainer New CoreContainer 2078135522
[junit4] 2> 37398 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node4]
[junit4] 2> 37403 WARN (jetty-launcher-155-thread-4) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node4/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node4/lib
[junit4] 2> 37403 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 37407 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 37407 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node3/.
[junit4] 2> 37407 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 37407 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 37407 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.CoreContainer New CoreContainer 1740267117
[junit4] 2> 37407 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node3]
[junit4] 2> 37415 WARN (jetty-launcher-155-thread-3) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node3/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node3/lib
[junit4] 2> 37415 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 37407 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34157/solr
[junit4] 2> 37417 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 37417 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37419 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 37419 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 37419 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 37419 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34157/solr
[junit4] 2> 37419 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 37419 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37419 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37428 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node2/.
[junit4] 2> 37431 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.CoreContainer New CoreContainer 236640146
[junit4] 2> 37431 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node2]
[junit4] 2> 37431 WARN (jetty-launcher-155-thread-2) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node2/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node2/lib
[junit4] 2> 37431 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 0x15660cbc3040003, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 37434 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 37434 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/.
[junit4] 2> 37431 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37435 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 1503006766
[junit4] 2> 37435 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1]
[junit4] 2> 37435 WARN (jetty-launcher-155-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/lib
[junit4] 2> 37435 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 37437 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 37437 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 37437 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 37437 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34157/solr
[junit4] 2> 37437 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 37437 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37437 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 37443 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 37443 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 37437 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37443 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34157/solr
[junit4] 2> 37459 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 37459 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37459 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37461 INFO (zkCallback-171-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@36226825 name:ZooKeeperConnection Watcher:127.0.0.1:34157 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37462 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37462 INFO (jetty-launcher-155-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37458 INFO (zkCallback-165-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ed5726f name:ZooKeeperConnection Watcher:127.0.0.1:34157 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37447 INFO (zkCallback-166-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@364b39e4 name:ZooKeeperConnection Watcher:127.0.0.1:34157 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37470 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37470 INFO (jetty-launcher-155-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37470 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37476 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37484 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$11/887415044@5a44dbe3
[junit4] 2> 37484 INFO (zkCallback-172-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2a8be3d7 name:ZooKeeperConnection Watcher:127.0.0.1:34157 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37484 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$11/887415044@6dd1b977
[junit4] 2> 37484 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37485 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37495 INFO (zkCallback-173-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@75aea90c name:ZooKeeperConnection Watcher:127.0.0.1:34157/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37495 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37495 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37496 INFO (jetty-launcher-155-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37496 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:44560_solr) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@21fb6de1 name:ZooKeeperConnection Watcher:127.0.0.1:34157/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37496 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37498 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 37498 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 37499 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$11/887415044@4b651f8
[junit4] 2> 37499 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37500 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$11/887415044@cae7210
[junit4] 2> 37500 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37503 INFO (zkCallback-175-thread-1-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4627f36 name:ZooKeeperConnection Watcher:127.0.0.1:34157/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37503 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37505 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 37505 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 37508 INFO (zkCallback-176-thread-1-processing-n:127.0.0.1:36438_solr) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b640c21 name:ZooKeeperConnection Watcher:127.0.0.1:34157/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37508 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 37508 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 37509 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37510 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 37512 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 37512 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 37512 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 37515 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 37516 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 37516 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 37516 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 37517 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 37518 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 37518 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 37534 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 37534 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 37534 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 37535 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 37535 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 37536 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 37536 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 37536 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 37537 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 37537 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 37538 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 37539 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 37540 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 37540 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 37540 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 37541 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 37542 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 37542 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 37542 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 37542 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 37542 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 37542 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 37543 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 37543 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 37543 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 37543 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 37546 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 37546 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 37546 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 37547 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 37548 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 37548 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 37548 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 37551 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 37552 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 37552 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 37552 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96370870301491209-127.0.0.1:35446_solr-n_0000000000
[junit4] 2> 37552 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96370870301491211-127.0.0.1:33483_solr-n_0000000001
[junit4] 2> 37552 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96370870301491210-127.0.0.1:44560_solr-n_0000000002
[junit4] 2> 37563 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35446_solr
[junit4] 2> 37563 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 37565 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96370870301491211-127.0.0.1:33483_solr-n_0000000001 to know if I could be the leader
[junit4] 2> 37565 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96370870301491209-127.0.0.1:35446_solr-n_0000000000 to know if I could be the leader
[junit4] 2> 37565 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44560_solr
[junit4] 2> 37565 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:44560_solr
[junit4] 2> 37565 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33483_solr
[junit4] 2> 37565 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:33483_solr
[junit4] 2> 37566 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.Overseer Overseer (id=96370870301491209-127.0.0.1:35446_solr-n_0000000000) starting
[junit4] 2> 37567 INFO (zkCallback-173-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 37567 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:44560_solr) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 37567 INFO (zkCallback-175-thread-1-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 37567 INFO (zkCallback-176-thread-1-processing-n:127.0.0.1:36438_solr) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 37567 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 37578 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:44560_solr) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 37579 INFO (zkCallback-173-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 37579 INFO (zkCallback-175-thread-1-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 37584 INFO (zkCallback-176-thread-1-processing-n:127.0.0.1:36438_solr) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 37592 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96370870301491212-127.0.0.1:36438_solr-n_0000000003
[junit4] 2> 37593 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96370870301491210-127.0.0.1:44560_solr-n_0000000002 to know if I could be the leader
[junit4] 2> 37594 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 37594 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:36438_solr as DOWN
[junit4] 2> 37596 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36438_solr
[junit4] 2> 37596 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:36438_solr
[junit4] 2> 37597 INFO (zkCallback-176-thread-1-processing-n:127.0.0.1:36438_solr) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 37598 INFO (zkCallback-175-thread-1-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 37598 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:44560_solr) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 37598 INFO (zkCallback-173-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [2])
[junit4] 2> 37599 INFO (zkCallback-176-thread-1-processing-n:127.0.0.1:36438_solr) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 37599 INFO (zkCallback-175-thread-1-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 37599 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:44560_solr) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 37603 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 37603 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 37603 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node2/.
[junit4] 2> 37604 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 37604 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1
[junit4] 2> 37604 INFO (jetty-launcher-155-thread-2) [n:127.0.0.1:33483_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 37611 INFO (zkCallback-173-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 37616 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 37617 INFO (OverseerCollectionConfigSetProcessor-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 37617 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 37617 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:35446_solr as DOWN
[junit4] 2> 37619 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35446_solr
[junit4] 2> 37619 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:35446_solr
[junit4] 2> 37619 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (3)
[junit4] 2> 37620 INFO (zkCallback-173-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 37620 INFO (zkCallback-175-thread-1-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 37620 INFO (zkCallback-176-thread-1-processing-n:127.0.0.1:36438_solr) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 37620 INFO (zkCallback-175-thread-1-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 37621 INFO (zkCallback-176-thread-1-processing-n:127.0.0.1:36438_solr) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 37621 INFO (zkCallback-173-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 37622 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:44560_solr) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 37623 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:44560_solr) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 37624 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:36438_solr"} current state version: 0
[junit4] 2> 37624 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:36438_solr
[junit4] 2> 37630 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:35446_solr"} current state version: 0
[junit4] 2> 37630 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:35446_solr
[junit4] 2> 37636 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 37636 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 37637 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/.
[junit4] 2> 37637 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 37639 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1
[junit4] 2> 37639 INFO (jetty-launcher-155-thread-1) [n:127.0.0.1:36438_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 37653 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 37653 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 37654 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node3/.
[junit4] 2> 37654 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 37660 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1
[junit4] 2> 37660 INFO (jetty-launcher-155-thread-3) [n:127.0.0.1:44560_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 37670 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 37670 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 37671 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node4/.
[junit4] 2> 37671 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 37672 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1
[junit4] 2> 37672 INFO (jetty-launcher-155-thread-4) [n:127.0.0.1:35446_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 37672 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37672 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37678 INFO (zkCallback-181-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4bd7791b name:ZooKeeperConnection Watcher:127.0.0.1:34157 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37678 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37678 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37687 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37687 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37690 INFO (zkCallback-184-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5a2e4503 name:ZooKeeperConnection Watcher:127.0.0.1:34157/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37690 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37690 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37690 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 37692 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 37692 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 37694 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig.xml
[junit4] 2> 37697 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/schema-sql.xml
[junit4] 2> 37699 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig-sql.xml
[junit4] 2> 37700 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig-slave1.xml
[junit4] 2> 37702 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/schema.xml
[junit4] 2> 37703 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/schema-replication1.xml
[junit4] 2> 37704 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/config/solrconfig-managed-schema.xml
[junit4] 2> 37725 INFO (TEST-TestCollectionStateWatchers.testWaitForStateChecksCurrentState-seed#[AF9A59AF351593BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWaitForStateChecksCurrentState
[junit4] 2> 37725 INFO (TEST-TestCollectionStateWatchers.testWaitForStateChecksCurrentState-seed#[AF9A59AF351593BA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 37725 INFO (TEST-TestCollectionStateWatchers.testWaitForStateChecksCurrentState-seed#[AF9A59AF351593BA]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 37727 INFO (zkCallback-185-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@79d3859d name:ZooKeeperConnection Watcher:127.0.0.1:34157 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 37727 INFO (TEST-TestCollectionStateWatchers.testWaitForStateChecksCurrentState-seed#[AF9A59AF351593BA]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 37727 INFO (TEST-TestCollectionStateWatchers.testWaitForStateChecksCurrentState-seed#[AF9A59AF351593BA]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 37732 INFO (qtp1513874853-438) [n:127.0.0.1:35446_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=61576f5b-c421-429d-af3a-9eb717ff0fe5&replicationFactor=1&collection.configName=config&name=waitforstate&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 37734 INFO (qtp1513874853-438) [n:127.0.0.1:35446_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=61576f5b-c421-429d-af3a-9eb717ff0fe5&replicationFactor=1&collection.configName=config&name=waitforstate&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 37735 INFO (OverseerCollectionConfigSetProcessor-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running/mn-61576f5b-c421-429d-af3a-9eb717ff0fe5
[junit4] 2> 37737 INFO (OverseerCollectionConfigSetProcessor-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "name":"waitforstate",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "collection.configName":"config",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "async":"61576f5b-c421-429d-af3a-9eb717ff0fe5",
[junit4] 2> "operation":"create"}
[junit4] 2> 37738 INFO (qtp1513874853-439) [n:127.0.0.1:35446_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=61576f5b-c421-429d-af3a-9eb717ff0fe5&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 37738 INFO (OverseerThreadFactory-73-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"waitforstate",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "collection.configName":"config",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "async":"61576f5b-c421-429d-af3a-9eb717ff0fe5",
[junit4] 2> "operation":"create"}
[junit4] 2> 37738 INFO (OverseerThreadFactory-73-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.OverseerCollectionMessageHandler Create collection waitforstate
[junit4] 2> 37738 INFO (qtp1513874853-439) [n:127.0.0.1:35446_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=61576f5b-c421-429d-af3a-9eb717ff0fe5&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 37739 INFO (OverseerThreadFactory-73-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.OverseerCollectionMessageHandler creating collections conf node /collections/waitforstate
[junit4] 2> 37739 INFO (OverseerThreadFactory-73-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/waitforstate
[junit4] 2> 37740 INFO (zkCallback-184-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 37740 INFO (zkCallback-174-thread-1-processing-n:127.0.0.1:44560_solr) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 37740 INFO (zkCallback-176-thread-1-processing-n:127.0.0.1:36438_solr) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 37740 INFO (zkCallback-175-thread-1-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 37741 INFO (zkCallback-173-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 37744 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"waitforstate",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "collection.configName":"config",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "async":"61576f5b-c421-429d-af3a-9eb717ff0fe5",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 37744 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: waitforstate
[junit4] 2> 37745 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/waitforstate/state.json
[junit4] 2> 37845 INFO (OverseerThreadFactory-73-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating SolrCores for new collection waitforstate, shardNames [shard1] , replicationFactor : 1
[junit4] 2> 37845 INFO (OverseerThreadFactory-73-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core waitforstate_shard1_replica1 as part of shard shard1 of collection waitforstate on 127.0.0.1:36438_solr
[junit4] 2> 37849 INFO (qtp1252502939-455) [n:127.0.0.1:36438_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925&qt=/admin/cores&collection.configName=config&name=waitforstate_shard1_replica1&action=CREATE&numShards=1&collection=waitforstate&shard=shard1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 37851 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr ] o.a.s.h.a.CoreAdminOperation core create command async=61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925&qt=/admin/cores&collection.configName=config&name=waitforstate_shard1_replica1&action=CREATE&numShards=1&collection=waitforstate&shard=shard1&wt=javabin&version=2
[junit4] 2> 37852 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=waitforstate_shard1_replica1, collection.configName=config, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=waitforstate, numShards=1, dataDir=data/, shard=shard1}
[junit4] 2> 37852 INFO (qtp1252502939-454) [n:127.0.0.1:36438_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925
[junit4] 2> 37852 INFO (qtp1252502939-454) [n:127.0.0.1:36438_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 37853 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 37854 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.ZkController Registering watch for collection waitforstate
[junit4] 2> 37854 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"waitforstate_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:36438/solr",
[junit4] 2> "node_name":"127.0.0.1:36438_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"waitforstate",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 37854 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"waitforstate_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:36438/solr",
[junit4] 2> "node_name":"127.0.0.1:36438_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"waitforstate",
[junit4] 2> "operation":"state"}
[junit4] 2> 37854 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.c.ZkStateReader Add data for [waitforstate] ver [0]
[junit4] 2> 37855 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 37957 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/waitforstate/state.json version: 0
[junit4] 2> 37959 INFO (zkCallback-176-thread-1-processing-n:127.0.0.1:36438_solr) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/waitforstate/state.json] for collection [waitforstate] has occurred - updating... (live nodes size: [4])
[junit4] 2> 37959 INFO (zkCallback-176-thread-1-processing-n:127.0.0.1:36438_solr) [n:127.0.0.1:36438_solr ] o.a.s.c.c.ZkStateReader Updating data for [waitforstate] from [0] to [1]
[junit4] 2> 38740 INFO (qtp1513874853-440) [n:127.0.0.1:35446_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=61576f5b-c421-429d-af3a-9eb717ff0fe5&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 38741 INFO (qtp1513874853-440) [n:127.0.0.1:35446_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=61576f5b-c421-429d-af3a-9eb717ff0fe5&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 38853 INFO (qtp1252502939-456) [n:127.0.0.1:36438_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925
[junit4] 2> 38853 INFO (qtp1252502939-456) [n:127.0.0.1:36438_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 38855 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for waitforstate_shard1_replica1
[junit4] 2> 38855 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:waitforstate
[junit4] 2> 38855 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 38855 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/waitforstate]
[junit4] 2> 38855 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.c.ZkStateReader path=[/collections/waitforstate] [configName]=[config] specified config exists in ZooKeeper
[junit4] 2> 38855 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/waitforstate_shard1_replica1'
[junit4] 2> 38856 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 38856 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 38857 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 38861 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 38864 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 38886 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 38896 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.s.IndexSchema [waitforstate_shard1_replica1] Schema name=test
[junit4] 2> 38960 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 38961 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 38962 WARN (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 38962 WARN (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 38963 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/config', cwd=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1
[junit4] 2> 38963 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 x:waitforstate_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'waitforstate_shard1_replica1' using configuration from collection waitforstate
[junit4] 2> 38963 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 38963 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.SolrCore [[waitforstate_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/waitforstate_shard1_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/./waitforstate_shard1_replica1/data/]
[junit4] 2> 38964 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.SolrCore JMX monitoring not detected for core: waitforstate_shard1_replica1
[junit4] 2> 38964 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/./waitforstate_shard1_replica1/data/snapshot_metadata
[junit4] 2> 38964 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot metadata file...
[junit4] 2> 38964 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/./waitforstate_shard1_replica1/data
[junit4] 2> 38964 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/./waitforstate_shard1_replica1/data/index/
[junit4] 2> 38964 WARN (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.SolrCore [waitforstate_shard1_replica1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/./waitforstate_shard1_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 38965 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/./waitforstate_shard1_replica1/data/index
[junit4] 2> 38965 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@650f9703 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@284b6b20),segFN=segments_1,generation=1}
[junit4] 2> 38965 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 38967 INFO (OldIndexDirectoryCleanupThreadForCore-waitforstate_shard1_replica1) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core waitforstate_shard1_replica1 in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/./waitforstate_shard1_replica1/data/
[junit4] 2> 38967 WARN (OldIndexDirectoryCleanupThreadForCore-waitforstate_shard1_replica1) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node1/./waitforstate_shard1_replica1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 38968 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 38970 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 38971 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 38971 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 38972 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 38973 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
[junit4] 2> 38973 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 38974 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 38974 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 38976 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 38976 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitforstate s:shard1 r:core_node1 x:waitforstate_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 38976 INFO (parallelCoreAdminExecutor-74-thread-1-processing-n:127.0.0.1:36438_solr 61576f5b-c421-429d-af3a-9eb717ff0fe51795612443183925 CREATE) [n:127.0.0.1:36438_solr c:waitfor
[...truncated too long message...]
1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node2/./falsepredicate_shard4_replica1/data/index
[junit4] 2> 69143 INFO (coreCloseExecutor-181-thread-2) [n:127.0.0.1:35446_solr c:testcollection s:shard2 r:core_node2 x:testcollection_shard2_replica1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node4/./testcollection_shard2_replica1/data/snapshot_metadata [CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node4/./testcollection_shard2_replica1/data/snapshot_metadata;done=false>>]
[junit4] 2> 69144 INFO (coreCloseExecutor-181-thread-2) [n:127.0.0.1:35446_solr c:testcollection s:shard2 r:core_node2 x:testcollection_shard2_replica1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 69144 INFO (coreCloseExecutor-181-thread-2) [n:127.0.0.1:35446_solr c:testcollection s:shard2 r:core_node2 x:testcollection_shard2_replica1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node4/./testcollection_shard2_replica1/data/snapshot_metadata
[junit4] 2> 69144 INFO (zkCallback-194-thread-3-processing-n:127.0.0.1:44560_solr) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader Updating data for [testcollection] from [5] to [6]
[junit4] 2> 69144 INFO (coreCloseExecutor-182-thread-3) [n:127.0.0.1:44560_solr c:testcollection s:shard3 r:core_node1 x:testcollection_shard3_replica1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 69144 INFO (zkCallback-173-thread-6-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testcollection/state.json] for collection [testcollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 69144 INFO (coreCloseExecutor-182-thread-3) [n:127.0.0.1:44560_solr c:testcollection s:shard3 r:core_node1 x:testcollection_shard3_replica1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node3/./testcollection_shard3_replica1/data
[junit4] 2> 69144 INFO (coreCloseExecutor-181-thread-1) [n:127.0.0.1:35446_solr c:falsepredicate s:shard2 r:core_node4 x:falsepredicate_shard2_replica1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 69144 INFO (coreCloseExecutor-181-thread-1) [n:127.0.0.1:35446_solr c:falsepredicate s:shard2 r:core_node4 x:falsepredicate_shard2_replica1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001/tempDir-001/node4/./falsepredicate_shard2_replica1/data/index
[junit4] 2> 69144 INFO (zkCallback-200-thread-2-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testcollection/state.json] for collection [testcollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 69145 INFO (zkCallback-200-thread-1-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 69145 INFO (zkCallback-173-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 69145 INFO (zkCallback-194-thread-3-processing-n:127.0.0.1:44560_solr) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 69145 INFO (zkCallback-173-thread-6-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader Updating data for [testcollection] from [5] to [6]
[junit4] 2> 69145 INFO (jetty-launcher-155-thread-4) [ ] o.a.s.c.Overseer Overseer (id=96370870301491209-127.0.0.1:35446_solr-n_0000000000) closing
[junit4] 2> 69145 INFO (OverseerStateUpdate-96370870301491209-127.0.0.1:35446_solr-n_0000000000) [n:127.0.0.1:35446_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:35446_solr
[junit4] 2> 69145 INFO (zkCallback-173-thread-1-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 69147 INFO (zkCallback-173-thread-6-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 69148 WARN (zkCallback-173-thread-6-processing-n:127.0.0.1:35446_solr) [n:127.0.0.1:35446_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 69148 INFO (jetty-launcher-155-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@56ba513f{/solr,null,UNAVAILABLE}
[junit4] 2> 70645 WARN (zkCallback-200-thread-1-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 70645 WARN (zkCallback-194-thread-3-processing-n:127.0.0.1:44560_solr) [n:127.0.0.1:44560_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 70645 WARN (zkCallback-200-thread-2-processing-n:127.0.0.1:33483_solr) [n:127.0.0.1:33483_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /collections/testcollection/state.json]
[junit4] 2> 70645 INFO (jetty-launcher-155-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@40e9a3fe{/solr,null,UNAVAILABLE}
[junit4] 2> 70645 INFO (jetty-launcher-155-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@60e31865{/solr,null,UNAVAILABLE}
[junit4] 2> 70647 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34157 34157
[junit4] 2> 70772 INFO (Thread-46) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34157 34157
[junit4] 2> 70772 WARN (Thread-46) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/aliases.json
[junit4] 2> 8 /solr/clusterprops.json
[junit4] 2> 7 /solr/security.json
[junit4] 2> 6 /solr/configs/config
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/collections/waitforstate/state.json
[junit4] 2> 8 /solr/clusterstate.json
[junit4] 2> 7 /solr/collections/falsepredicate/state.json
[junit4] 2> 7 /solr/collections/testcollection/state.json
[junit4] 2> 3 /solr/collections/currentstate/state.json
[junit4] 2> 3 /solr/overseer_elect/election/96370870301491209-127.0.0.1:35446_solr-n_0000000000
[junit4] 2> 2 /solr/overseer_elect/election/96370870301491211-127.0.0.1:33483_solr-n_0000000001
[junit4] 2> 2 /solr/collections/delayed/state.json
[junit4] 2> 2 /solr/collections/tobedeleted/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 47 /solr/overseer/queue
[junit4] 2> 17 /solr/overseer/queue-work
[junit4] 2> 13 /solr/overseer/collection-queue-work
[junit4] 2> 8 /solr/collections
[junit4] 2> 5 /solr/live_nodes
[junit4] 2>
[junit4] 2> 70772 INFO (SUITE-TestCollectionStateWatchers-seed#[AF9A59AF351593BA]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.common.cloud.TestCollectionStateWatchers_AF9A59AF351593BA-001
[junit4] 2> Aug 06, 2016 5:00:15 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=1074, maxMBSortInHeap=5.438586183653819, sim=RandomSimilarity(queryNorm=true): {}, locale=en-MT, timezone=Europe/Samara
[junit4] 2> NOTE: Linux 4.4.0-31-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=12,threads=1,free=283841560,total=526385152
[junit4] 2> NOTE: All tests run in this JVM: [GraphExpressionTest, SolrExampleEmbeddedTest, TestConfigSetAdminRequest, SolrExampleStreamingBinaryTest, TestCollectionStateWatchers]
[junit4] Completed [57/85 (1!)] on J1 in 34.59s, 9 tests, 1 failure <<< FAILURES!
[...truncated 59489 lines...]
[JENKINS-EA] Lucene-Solr-master-Linux (64bit/jdk-9-ea+129) - Build
# 17483 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17483/
Java: 64bit/jdk-9-ea+129 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasics
Error Message:
IOException occured when talking to server at: http://127.0.0.1:37238/solr/testSolrCloudCollection_shard1_replica2
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: IOException occured when talking to server at: http://127.0.0.1:37238/solr/testSolrCloudCollection_shard1_replica2
at __randomizedtesting.SeedInfo.seed([9B10E97247B2D825:A6C8475E7F5C8655]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:760)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1172)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1061)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:997)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at org.apache.solr.security.BasicAuthIntegrationTest.doExtraTests(BasicAuthIntegrationTest.java:193)
at org.apache.solr.cloud.TestMiniSolrCloudClusterBase.testCollectionCreateSearchDelete(TestMiniSolrCloudClusterBase.java:196)
at org.apache.solr.cloud.TestMiniSolrCloudClusterBase.testBasics(TestMiniSolrCloudClusterBase.java:79)
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:533)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:37238/solr/testSolrCloudCollection_shard1_replica2
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:622)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:366)
at org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:735)
at java.util.concurrent.FutureTask.run(java.base@9-ea/FutureTask.java:264)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@9-ea/ThreadPoolExecutor.java:1158)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@9-ea/ThreadPoolExecutor.java:632)
... 1 more
Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:37238 failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:165)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:167)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:513)
... 10 more
Build Log:
[...truncated 12435 lines...]
[junit4] Suite: org.apache.solr.security.BasicAuthIntegrationTest
[junit4] 2> 1792657 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1792657 INFO (Thread-4051) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1792657 INFO (Thread-4051) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1792757 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.ZkTestServer start zk server on port:45554
[junit4] 2> 1792757 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1792758 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792806 INFO (zkCallback-27319-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@39b67f63 name:ZooKeeperConnection Watcher:127.0.0.1:45554 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792806 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792807 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1792807 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 1792862 INFO (jetty-launcher-27318-thread-5) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1792862 INFO (jetty-launcher-27318-thread-4) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1792862 INFO (jetty-launcher-27318-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1792862 INFO (jetty-launcher-27318-thread-3) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1792862 INFO (jetty-launcher-27318-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1792863 INFO (jetty-launcher-27318-thread-5) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6c95149e{/solr,null,AVAILABLE}
[junit4] 2> 1792863 INFO (jetty-launcher-27318-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@45a3eb1f{/solr,null,AVAILABLE}
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d5fb579{/solr,null,AVAILABLE}
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-5) [ ] o.e.j.s.ServerConnector Started ServerConnector@2615832d{HTTP/1.1,[http/1.1]}{127.0.0.1:40614}
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-4) [ ] o.e.j.s.ServerConnector Started ServerConnector@60bba13f{HTTP/1.1,[http/1.1]}{127.0.0.1:41943}
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@507b45f8{/solr,null,AVAILABLE}
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@34788dca{HTTP/1.1,[http/1.1]}{127.0.0.1:37238}
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-4) [ ] o.e.j.s.Server Started @1794977ms
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@60441642{/solr,null,AVAILABLE}
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-3) [ ] o.e.j.s.ServerConnector Started ServerConnector@2388a260{HTTP/1.1,[http/1.1]}{127.0.0.1:37085}
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-5) [ ] o.e.j.s.Server Started @1794977ms
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-3) [ ] o.e.j.s.Server Started @1794977ms
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40614}
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37085}
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@3db23933{HTTP/1.1,[http/1.1]}{127.0.0.1:42883}
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): jdk.internal.loader.ClassLoaders$AppClassLoader@3ab39c39
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): jdk.internal.loader.ClassLoaders$AppClassLoader@3ab39c39
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node5'
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node3'
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41943}
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): jdk.internal.loader.ClassLoaders$AppClassLoader@3ab39c39
[junit4] 2> 1792864 INFO (jetty-launcher-27318-thread-1) [ ] o.e.j.s.Server Started @1794977ms
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node4'
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37238}
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-2) [ ] o.e.j.s.Server Started @1794978ms
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): jdk.internal.loader.ClassLoaders$AppClassLoader@3ab39c39
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node1'
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42883}
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): jdk.internal.loader.ClassLoaders$AppClassLoader@3ab39c39
[junit4] 2> 1792865 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node2'
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1792866 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1792867 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792878 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1792879 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792887 INFO (zkCallback-27320-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7532d17e name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792887 INFO (zkCallback-27322-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6fb2ec82 name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792887 INFO (zkCallback-27321-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@58a8a17 name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792887 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792887 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792887 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1792887 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792888 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1792888 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1792895 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1792895 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1792895 INFO (zkCallback-27323-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@555d0ead name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792895 INFO (zkCallback-27324-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@328915df name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792895 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1792895 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792895 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792896 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1792896 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1792896 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1792896 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1792903 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node1/.
[junit4] 2> 1792903 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 717657111
[junit4] 2> 1792903 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node1]
[junit4] 2> 1792903 WARN (jetty-launcher-27318-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node1/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node1/lib
[junit4] 2> 1792903 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 1792904 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node4/.
[junit4] 2> 1792904 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 0x1566157c9bc0002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
[junit4] 2> 1792904 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node3/.
[junit4] 2> 1792905 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.CoreContainer New CoreContainer 229691546
[junit4] 2> 1792905 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node3]
[junit4] 2> 1792905 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1792905 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1792905 WARN (jetty-launcher-27318-thread-3) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node3/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node3/lib
[junit4] 2> 1792905 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 1792904 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node5/.
[junit4] 2> 1792906 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.CoreContainer New CoreContainer 493397231
[junit4] 2> 1792906 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node5]
[junit4] 2> 1792904 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.CoreContainer New CoreContainer 1317183347
[junit4] 2> 1792905 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1792907 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node4]
[junit4] 2> 1792907 WARN (jetty-launcher-27318-thread-5) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node5/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node5/lib
[junit4] 2> 1792907 WARN (jetty-launcher-27318-thread-4) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node4/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node4/lib
[junit4] 2> 1792907 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 1792907 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 1792907 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45554/solr
[junit4] 2> 1792907 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1792907 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1792908 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792908 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1792908 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1792908 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1792908 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1792908 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1792908 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45554/solr
[junit4] 2> 1792908 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45554/solr
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45554/solr
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node2/.
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.CoreContainer New CoreContainer 834958392
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node2]
[junit4] 2> 1792909 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1792910 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792910 WARN (jetty-launcher-27318-thread-2) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node2/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node2/lib
[junit4] 2> 1792910 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792910 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 1792911 INFO (zkCallback-27327-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6c960dac name:ZooKeeperConnection Watcher:127.0.0.1:45554 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792911 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792911 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1792912 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1792912 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1792912 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1792912 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45554/solr
[junit4] 2> 1792912 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1792912 INFO (zkCallback-27335-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@54e03820 name:ZooKeeperConnection Watcher:127.0.0.1:45554 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792912 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1792912 INFO (zkCallback-27336-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@97ecb00 name:ZooKeeperConnection Watcher:127.0.0.1:45554 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792912 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792913 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792913 INFO (zkCallback-27334-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@48291f8c name:ZooKeeperConnection Watcher:127.0.0.1:45554 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792913 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792913 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1792913 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1792913 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792913 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1792914 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$144/99608060@7b09cca8
[junit4] 2> 1792914 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792916 INFO (zkCallback-27339-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@60bce147 name:ZooKeeperConnection Watcher:127.0.0.1:45554 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792916 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792916 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1792919 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$144/99608060@662bee6e
[junit4] 2> 1792921 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$144/99608060@7de1457a
[junit4] 2> 1792925 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$144/99608060@1e66748f
[junit4] 2> 1792925 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792925 INFO (zkCallback-27340-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@39084021 name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792925 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792925 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$144/99608060@4688861b
[junit4] 2> 1792925 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792925 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792926 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792927 INFO (zkCallback-27341-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6f4d1cd0 name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792927 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792927 INFO (zkCallback-27342-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4736941a name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792928 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792929 INFO (zkCallback-27343-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1e45e1b1 name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792929 INFO (zkCallback-27344-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2a31a4a name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1792929 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792929 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792931 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1792932 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1792932 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1792932 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1792932 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1792952 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1792955 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1792955 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1792955 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1792955 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1792990 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1792991 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1792992 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1792994 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1792994 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1793003 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1793003 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1793003 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1793003 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1793004 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1793024 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1793024 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1793024 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1793026 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1793027 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1793044 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1793044 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1793045 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1793044 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1793045 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1793049 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1793051 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1793051 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1793051 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1793051 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1793065 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1793065 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1793065 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1793065 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1793065 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1793079 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1793083 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1793083 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1793083 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1793083 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1793088 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1793088 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1793088 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1793088 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1793088 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1793094 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1793094 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1793097 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1793098 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1793098 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1793099 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1793099 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1793099 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1793099 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1793099 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1793099 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1793099 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1793099 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1793099 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1793099 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1793100 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1793100 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1793100 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1793100 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1793100 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1793121 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1793125 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1793125 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1793125 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1793125 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1793131 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1793131 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1793139 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1793164 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1793164 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96371471709634575-127.0.0.1:42883_solr-n_0000000001
[junit4] 2> 1793164 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96371471709634574-127.0.0.1:37085_solr-n_0000000000
[junit4] 2> 1793167 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96371471709634573-127.0.0.1:40614_solr-n_0000000002
[junit4] 2> 1793167 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1793169 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96371471709634571-127.0.0.1:37238_solr-n_0000000003
[junit4] 2> 1793169 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37085_solr
[junit4] 2> 1793169 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1793178 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96371471709634572-127.0.0.1:41943_solr-n_0000000004
[junit4] 2> 1793178 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96371471709634574-127.0.0.1:37085_solr-n_0000000000 to know if I could be the leader
[junit4] 2> 1793178 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96371471709634575-127.0.0.1:42883_solr-n_0000000001 to know if I could be the leader
[junit4] 2> 1793178 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96371471709634573-127.0.0.1:40614_solr-n_0000000002 to know if I could be the leader
[junit4] 2> 1793178 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42883_solr
[junit4] 2> 1793179 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40614_solr
[junit4] 2> 1793179 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:42883_solr
[junit4] 2> 1793179 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:40614_solr
[junit4] 2> 1793179 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96371471709634571-127.0.0.1:37238_solr-n_0000000003 to know if I could be the leader
[junit4] 2> 1793205 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37238_solr
[junit4] 2> 1793205 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:37238_solr
[junit4] 2> 1793205 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41943_solr
[junit4] 2> 1793205 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:41943_solr
[junit4] 2> 1793205 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.Overseer Overseer (id=96371471709634574-127.0.0.1:37085_solr-n_0000000000) starting
[junit4] 2> 1793208 INFO (zkCallback-27344-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 1793208 INFO (zkCallback-27343-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 1793208 INFO (zkCallback-27342-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 1793208 INFO (zkCallback-27340-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 1793208 INFO (zkCallback-27341-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 1793213 INFO (zkCallback-27344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1793213 INFO (zkCallback-27343-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1793213 INFO (zkCallback-27340-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1793213 INFO (zkCallback-27342-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1793213 INFO (zkCallback-27341-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1793216 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1793217 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1793217 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node5/.
[junit4] 2> 1793218 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1793218 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1793219 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node2/.
[junit4] 2> 1793221 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1793221 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1793225 INFO (zkCallback-27343-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1793225 INFO (zkCallback-27342-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1793225 INFO (zkCallback-27341-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1793225 INFO (zkCallback-27340-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1793225 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1793225 INFO (zkCallback-27343-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1793225 INFO (zkCallback-27342-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1793225 INFO (jetty-launcher-27318-thread-5) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1793225 INFO (zkCallback-27344-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3])
[junit4] 2> 1793225 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1793225 INFO (jetty-launcher-27318-thread-2) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1793226 INFO (zkCallback-27341-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1793227 INFO (zkCallback-27344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1793227 INFO (zkCallback-27340-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1793227 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1793227 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1793227 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1793227 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node1/.
[junit4] 2> 1793228 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1793231 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1793231 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1793231 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node4/.
[junit4] 2> 1793232 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1793239 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1793239 INFO (jetty-launcher-27318-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1793239 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1793239 INFO (jetty-launcher-27318-thread-4) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1793316 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1793316 INFO (OverseerCollectionConfigSetProcessor-96371471709634574-127.0.0.1:37085_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 1793316 INFO (OverseerStateUpdate-96371471709634574-127.0.0.1:37085_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1793317 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:37085_solr as DOWN
[junit4] 2> 1793319 INFO (OverseerStateUpdate-96371471709634574-127.0.0.1:37085_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (4)
[junit4] 2> 1793330 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37085_solr
[junit4] 2> 1793330 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:37085_solr
[junit4] 2> 1793331 INFO (OverseerStateUpdate-96371471709634574-127.0.0.1:37085_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:37085_solr"} current state version: 0
[junit4] 2> 1793331 INFO (OverseerStateUpdate-96371471709634574-127.0.0.1:37085_solr-n_0000000000) [ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:37085_solr
[junit4] 2> 1793340 INFO (zkCallback-27344-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1793340 INFO (zkCallback-27341-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1793340 INFO (zkCallback-27340-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1793340 INFO (zkCallback-27344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1793340 INFO (zkCallback-27343-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1793340 INFO (zkCallback-27342-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1793344 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1793344 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1793344 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node3/.
[junit4] 2> 1793344 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1793349 INFO (zkCallback-27341-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1793349 INFO (zkCallback-27343-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1793349 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1793349 INFO (jetty-launcher-27318-thread-3) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1793349 INFO (zkCallback-27342-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1793349 INFO (zkCallback-27340-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1793349 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1793350 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1793352 INFO (zkCallback-27350-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2dbce88f name:ZooKeeperConnection Watcher:127.0.0.1:45554 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1793352 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1793352 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1793356 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@2615832d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1793356 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=493397231
[junit4] 2> 1793356 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:40614_solr as DOWN
[junit4] 2> 1793367 INFO (OverseerStateUpdate-96371471709634574-127.0.0.1:37085_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:40614_solr"} current state version: 0
[junit4] 2> 1793367 INFO (OverseerStateUpdate-96371471709634574-127.0.0.1:37085_solr-n_0000000000) [ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:40614_solr
[junit4] 2> 1793371 INFO (zkCallback-27344-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [5])
[junit4] 2> 1793371 INFO (zkCallback-27341-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [5])
[junit4] 2> 1793371 INFO (zkCallback-27342-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [5])
[junit4] 2> 1793371 INFO (zkCallback-27343-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [5])
[junit4] 2> 1793371 WARN (zkCallback-27342-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1793371 INFO (zkCallback-27344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1793371 INFO (zkCallback-27340-thread-2) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [5])
[junit4] 2> 1793371 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6c95149e{/solr,null,UNAVAILABLE}
[junit4] 2> 1793372 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1793375 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7a6f67b5{/solr,null,AVAILABLE}
[junit4] 2> 1793375 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.e.j.s.ServerConnector Started ServerConnector@f90ba93{HTTP/1.1,[http/1.1]}{127.0.0.1:45273}
[junit4] 2> 1793376 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.e.j.s.Server Started @1795489ms
[junit4] 2> 1793376 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45273}
[junit4] 2> 1793376 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): jdk.internal.loader.ClassLoaders$AppClassLoader@3ab39c39
[junit4] 2> 1793376 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node6'
[junit4] 2> 1793376 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1793376 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1793376 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1793377 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1793386 INFO (zkCallback-27341-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1793386 INFO (zkCallback-27343-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1793387 INFO (zkCallback-27340-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (4)
[junit4] 2> 1793386 INFO (zkCallback-27340-thread-1) [ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96371471709634575-127.0.0.1:42883_solr-n_0000000001 to know if I could be the leader
[junit4] 2> 1793402 INFO (zkCallback-27352-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@49552d72 name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1793402 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1793402 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1793402 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1793412 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node6/.
[junit4] 2> 1793412 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.CoreContainer New CoreContainer 1666294230
[junit4] 2> 1793412 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node6]
[junit4] 2> 1793412 WARN (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node6/lib to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node6/lib
[junit4] 2> 1793412 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,
[junit4] 2> 1793414 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
[junit4] 2> 1793414 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1793414 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1793414 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45554/solr
[junit4] 2> 1793414 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1793414 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1793414 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1793423 INFO (zkCallback-27355-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7dd63203 name:ZooKeeperConnection Watcher:127.0.0.1:45554 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1793423 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1793423 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1793432 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$144/99608060@cbf6a4
[junit4] 2> 1793433 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1793441 INFO (zkCallback-27356-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@152e3b9c name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1793441 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1793476 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1793476 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1793476 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1793477 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1793480 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96371471709634579-127.0.0.1:45273_solr-n_0000000005
[junit4] 2> 1793480 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/96371471709634572-127.0.0.1:41943_solr-n_0000000004 to know if I could be the leader
[junit4] 2> 1793480 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:45273_solr as DOWN
[junit4] 2> 1793484 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45273_solr
[junit4] 2> 1793484 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:45273_solr
[junit4] 2> 1793484 INFO (OverseerStateUpdate-96371471709634574-127.0.0.1:37085_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:45273_solr"} current state version: 0
[junit4] 2> 1793484 INFO (OverseerStateUpdate-96371471709634574-127.0.0.1:37085_solr-n_0000000000) [ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:45273_solr
[junit4] 2> 1793501 INFO (zkCallback-27341-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1793501 INFO (zkCallback-27343-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1793501 INFO (zkCallback-27344-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1793501 INFO (zkCallback-27341-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1793501 INFO (zkCallback-27356-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1793501 INFO (zkCallback-27340-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [4])
[junit4] 2> 1793503 INFO (zkCallback-27344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1793503 INFO (zkCallback-27356-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1793504 INFO (zkCallback-27343-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1793503 INFO (zkCallback-27340-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1793505 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1793505 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1793506 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001/tempDir-001/node6/.
[junit4] 2> 1793506 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1793507 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1793507 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1793507 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1793507 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1793514 INFO (zkCallback-27358-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@603f8d84 name:ZooKeeperConnection Watcher:127.0.0.1:45554/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1793514 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1793515 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1793515 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/protwords.txt
[junit4] 2> 1793529 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/solrconfig-delaying-component.xml
[junit4] 2> 1793532 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/bad_solrconfig.xml
[junit4] 2> 1793549 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/solrconfig-sortingmergepolicyfactory.xml
[junit4] 2> 1793551 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/solrconfig-nocache.xml
[junit4] 2> 1793576 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/solrconfig-implicitproperties.xml
[junit4] 2> 1793582 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/solrconfig-mergepolicy-legacy.xml
[junit4] 2> 1793601 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/bad-schema-uniquekey-uses-default.xml
[junit4] 2> 1793603 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/bad-schema-sim-default-has-no-explicit-sim.xml
[junit4] 2> 1793617 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/bad-schema-uniquekey-multivalued.xml
[junit4] 2> 1793633 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/analyzingInfixSuggest.txt
[junit4] 2> 1793636 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/solrconfig-testxmlparser.xml
[junit4] 2> 1793645 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/missleading.extension.updateprocessor.js.txt
[junit4] 2> 1793663 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/bad-schema-multiple-asterisk-copyfield-source-should-fail-test.xml
[junit4] 2> 1793677 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/schema-replication2.xml
[junit4] 2> 1793680 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/solrconfig-infostream-logging.xml
[junit4] 2> 1793686 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/solrconfig-collapseqparser.xml
[junit4] 2> 1793700 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/bad-schema-currency-ft-bogus-code-in-xml.xml
[junit4] 2> 1793703 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/old_synonyms.txt
[junit4] 2> 1793712 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/solrCloudCollectionConfig/enumsConfig.xml
[junit4] 2> 1793719 INFO (TEST-BasicAuthIntegrationTest.testBasics-seed#[9B10E97247B2D825]) [ ] o.a.s.c.c.SolrZk
[...truncated too long message...]
ava.base@9-ea/Native Method)
[junit4] > at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
[junit4] > at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
[junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:37238/solr/testSolrCloudCollection_shard1_replica2
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:622)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:413)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:366)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:735)
[junit4] > at java.util.concurrent.FutureTask.run(java.base@9-ea/FutureTask.java:264)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(java.base@9-ea/ThreadPoolExecutor.java:1158)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(java.base@9-ea/ThreadPoolExecutor.java:632)
[junit4] > ... 1 more
[junit4] > Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:37238 failed to respond
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
[junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:165)
[junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:167)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
[junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271)
[junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
[junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
[junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:513)
[junit4] > ... 10 more
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.BasicAuthIntegrationTest_9B10E97247B2D825-001
[junit4] 2> Aug 06, 2016 7:32:58 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {range_facet_l_dv=Lucene50(blocksize=128), _version_=PostingsFormat(name=LuceneVarGapFixedInterval), multiDefault=FSTOrd50, intDefault=PostingsFormat(name=LuceneVarGapFixedInterval), id=Lucene50(blocksize=128), range_facet_i_dv=FSTOrd50, range_facet_l=FSTOrd50, timestamp=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Lucene54), timestamp=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=780, maxMBSortInHeap=6.18230914526495, sim=RandomSimilarity(queryNorm=true): {}, locale=eo-001, timezone=Europe/Vaduz
[junit4] 2> NOTE: Linux 4.4.0-31-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=265217408,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestDownShardTolerantSearch, PathHierarchyTokenizerFactoryTest, TestSolr4Spatial, PreAnalyzedFieldTest, TestTolerantUpdateProcessorRandomCloud, FacetPivotSmallTest, TestSolrCloudWithDelegationTokens, TestRealTimeGet, SolrXmlInZkTest, ResponseHeaderTest, CursorPagingTest, FileBasedSpellCheckerTest, DistributedExpandComponentTest, CurrencyFieldOpenExchangeTest, TestAddFieldRealTimeGet, StandardRequestHandlerTest, TestBlendedInfixSuggestions, TestDocTermOrds, PeerSyncTest, TestCollectionAPI, CopyFieldTest, TestPhraseSuggestions, TestMergePolicyConfig, PreAnalyzedUpdateProcessorTest, TestNumericTerms32, TestRTGBase, TestOmitPositions, TestIBSimilarityFactory, TestRandomDVFaceting, CoreAdminRequestStatusTest, LeaderInitiatedRecoveryOnCommitTest, DeleteStatusTest, HighlighterMaxOffsetTest, TestRecoveryHdfs, TestCrossCoreJoin, TestCollationFieldDocValues, TestSolrConfigHandler, UUIDUpdateProcessorFallbackTest, LeaderElectionIntegrationTest, TestNoOpRegenerator, RAMDirectoryFactoryTest, TestLRUCache, AlternateDirectoryTest, TestArbitraryIndexDir, StressHdfsTest, TestDistributedSearch, ZkNodePropsTest, TestValueSourceCache, HdfsNNFailoverTest, DistributedQueryComponentCustomSortTest, HdfsTlogReplayBufferedWhileIndexingTest, TestNRTOpen, PrimUtilsTest, TestJoin, DocExpirationUpdateProcessorFactoryTest, TestFilteredDocIdSet, CloudMLTQParserTest, RemoteQueryErrorTest, ConnectionReuseTest, TestReplicationHandler, BasicZkTest, TestLazyCores, PolyFieldTest, DocumentAnalysisRequestHandlerTest, SolrCoreTest, ActionThrottleTest, DateFieldTest, TestConfigSetProperties, TestHighlightDedupGrouping, SystemInfoHandlerTest, DistribJoinFromCollectionTest, TestPivotHelperCode, TestSolrConfigHandlerCloud, TestReplicaProperties, SimpleCollectionCreateDeleteTest, TestReqParamsAPI, BufferStoreTest, TestLuceneMatchVersion, DeleteLastCustomShardedReplicaTest, HdfsChaosMonkeyNothingIsSafeTest, AsyncCallRequestStatusResponseTest, QueryElevationComponentTest, SynonymTokenizerTest, TestConfigSetsAPIZkFailure, TestSolrQueryParserResource, BlockJoinFacetRandomTest, AnalysisAfterCoreReloadTest, TestRawResponseWriter, EnumFieldTest, ExternalFileFieldSortTest, EchoParamsTest, TestComponentsName, TestFaceting, TestRTimerTree, SOLR749Test, TestHdfsUpdateLog, TestFieldCacheSanityChecker, DisMaxRequestHandlerTest, URLClassifyProcessorTest, OverseerStatusTest, TestSortingResponseWriter, OutputWriterTest, TestRemoteStreaming, TestFieldResource, BasicDistributedZkTest, SyncSliceTest, OverseerTest, ClusterStateUpdateTest, TestRandomFaceting, TestRecovery, TermVectorComponentDistributedTest, HardAutoCommitTest, TestCoreContainer, StatsComponentTest, TestFunctionQuery, TestBadConfig, TestIndexSearcher, HighlighterTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SpellCheckCollatorTest, SolrRequestParserTest, SuggesterTSTTest, TestTrie, SpatialFilterTest, TestCSVLoader, WordBreakSolrSpellCheckerTest, TestAtomicUpdateErrorCases, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, XsltUpdateRequestHandlerTest, TestQueryUtils, PrimitiveFieldTypeTest, TermsComponentTest, RequiredFieldsTest, IndexSchemaRuntimeFieldTest, TestJmxIntegration, UpdateRequestProcessorFactoryTest, TestCSVResponseWriter, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, CSVRequestHandlerTest, TestLFUCache, CleanupOldIndexTest, CollectionsAPIAsyncDistributedZkTest, CreateCollectionCleanupTest, DeleteShardTest, DistributedQueueTest, LeaderFailoverAfterPartitionTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, OverseerRolesTest, TestAuthenticationFramework, TestExclusionRuleCollectionAccess, TestLocalFSCloudBackupRestore, TestMiniSolrCloudClusterBase, TestOnReconnectListenerSupport, TestRandomFlRTGCloud, TestRandomRequestDistribution, TestRequestStatusCollectionAPI, TestSizeLimitedDistributedMap, TestSolrCloudWithSecureImpersonation, TestStressLiveNodes, TestHdfsBackupRestoreCore, TestSQLHandler, CoreAdminCreateDiscoverTest, CoreMergeIndexesAdminHandlerTest, SecurityConfHandlerTest, DistributedDebugComponentTest, DistributedFacetPivotLargeTest, SpatialHeatmapFacetsTest, TestGeoJSONResponseWriter, TestSubQueryTransformerCrossCore, TestManagedResource, TestManagedResourceStorage, TestRestManager, TestCopyFieldCollectionResource, TestDynamicFieldCollectionResource, TestDynamicFieldResource, TestFieldCollectionResource, TestFieldTypeResource, TestSchemaNameResource, TestSchemaResource, TestSchemaVersionResource, TestSerializedLuceneMatchVersion, TestSolrQueryParserDefaultOperatorResource, TestManagedStopFilterFactory, TestManagedSynonymFilterFactory, BadCopyFieldTest, BooleanFieldTest, ChangedSchemaMergeTest, DateRangeFieldTest, DocValuesMissingTest, SpatialRPTFieldTypeTest, TestCloudManagedSchema, TestQueryWrapperFilter, TestReloadDeadlock, TestSimpleQParserPlugin, TestSolrFieldCacheMBean, TestStandardQParsers, TestStressUserVersions, TestTrieFacet, TestXmlQParserPlugin, TestJsonFacets, TestSortByMinMaxFunction, BlockJoinFacetDistribTest, TestExactSharedStatsCache, TestExactStatsCache, BasicAuthIntegrationTest]
[junit4] Completed [590/626 (1!)] on J0 in 20.76s, 1 test, 1 error <<< FAILURES!
[...truncated 49785 lines...]