Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1094/
1 tests failed. FAILED: org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh Error Message: Could not find collection : c1 Stack Trace: org.apache.solr.common.SolrException: Could not find collection : c1 at __randomizedtesting.SeedInfo.seed([106C213269B6897F:FD650C5B9D64FBA]:0) at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170) at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:135) at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:41) 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 11179 lines...] [junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_106C213269B6897F-001/init-core-data-001 [junit4] 2> 1039156 INFO (SUITE-ZkStateReaderTest-seed#[106C213269B6897F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1039158 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy [junit4] 2> 1039158 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1039158 INFO (Thread-2522) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1039158 INFO (Thread-2522) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1039260 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer start zk server on port:41270 [junit4] 2> 1039260 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1039265 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1039301 INFO (zkCallback-1357-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6a62c616 name:ZooKeeperConnection Watcher:127.0.0.1:41270 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1039301 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1039301 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1039306 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1039306 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 0x1543568b5210000, 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> 1039307 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1039308 INFO (zkCallback-1358-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3d78aa0f name:ZooKeeperConnection Watcher:127.0.0.1:41270 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1039308 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1039308 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1039308 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1039311 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1039312 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1039317 INFO (zkCallback-1359-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@62f014d9 name:ZooKeeperConnection Watcher:127.0.0.1:41270/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1039317 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1039317 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1039317 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1039318 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1039319 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1039319 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1039320 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1039321 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1039321 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1039340 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1039349 INFO (zkCallback-1359-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1039364 INFO (zkCallback-1359-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1039576 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1039592 INFO (zkCallback-1359-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1039649 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41270 41270 [junit4] 2> 1039963 INFO (Thread-2522) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41270 41270 [junit4] 2> 1039968 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy [junit4] 2> 1039996 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy [junit4] 2> 1039997 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1040008 INFO (Thread-2523) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1040008 INFO (Thread-2523) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1040104 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer start zk server on port:38751 [junit4] 2> 1040104 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1040115 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1040136 INFO (zkCallback-1360-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@28f3456a name:ZooKeeperConnection Watcher:127.0.0.1:38751 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1040136 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1040137 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1040169 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1040175 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1040200 INFO (zkCallback-1361-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@570315b1 name:ZooKeeperConnection Watcher:127.0.0.1:38751 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1040204 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1040204 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1040204 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1040232 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 0x1543568b8740001, 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> 1040232 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1040233 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1040243 INFO (zkCallback-1362-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@684e3ac4 name:ZooKeeperConnection Watcher:127.0.0.1:38751/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1040243 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1040244 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1040244 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1040244 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1040245 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1040245 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1040246 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1040246 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1040247 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1040247 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1040248 INFO (zkCallback-1362-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1040248 INFO (zkCallback-1362-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1040298 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1040300 INFO (zkCallback-1362-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1040469 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38751 38751 [junit4] 2> 1040649 INFO (Thread-2523) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38751 38751 [junit4] 2> 1040656 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy [junit4] 2> 1040658 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh [junit4] 2> 1040659 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1040663 INFO (Thread-2524) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1040663 INFO (Thread-2524) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1040763 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer start zk server on port:38124 [junit4] 2> 1040764 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1040772 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1040800 INFO (zkCallback-1363-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a89d1fb name:ZooKeeperConnection Watcher:127.0.0.1:38124 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1040801 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1040802 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1040824 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1040840 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1040851 INFO (zkCallback-1364-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@131360b7 name:ZooKeeperConnection Watcher:127.0.0.1:38124 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1040851 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1040852 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1040852 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1040860 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1040860 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1040861 INFO (zkCallback-1365-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a65e2fe name:ZooKeeperConnection Watcher:127.0.0.1:38124/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1040861 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1040861 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1040864 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1040870 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1040871 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1040872 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1040874 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1040875 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1040876 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1040876 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader addZkWatch [c1] [junit4] 2> 1040876 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 1040876 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1040877 INFO (zkCallback-1365-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1040900 INFO (zkCallback-1365-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1041010 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1041028 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]-EventThread) [ ] o.a.z.ClientCnxn Error while calling watcher [junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$6/1267436630@1a74f507 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@1e4b6291[Shutting down, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 3] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214) [junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498) [junit4] 2> 1041029 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]-EventThread) [ ] o.a.z.ClientCnxn Error while calling watcher [junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$6/1267436630@573919b5 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@1e4b6291[Shutting down, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 3] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214) [junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498) [junit4] 2> 1041040 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38124 38124 [junit4] 2> 1041217 INFO (Thread-2524) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38124 38124 [junit4] 2> 1041217 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithExplicitRefresh -Dtests.seed=106C213269B6897F -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=fi -Dtests.timezone=Atlantic/Faroe -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.56s J0 | ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh <<< [junit4] > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1 [junit4] > at __randomizedtesting.SeedInfo.seed([106C213269B6897F:FD650C5B9D64FBA]:0) [junit4] > at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170) [junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:135) [junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:41) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1041221 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay [junit4] 2> 1041221 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1041228 INFO (Thread-2525) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1041228 INFO (Thread-2525) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1041324 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer start zk server on port:51455 [junit4] 2> 1041324 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1041325 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1041330 INFO (zkCallback-1366-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@312b4b51 name:ZooKeeperConnection Watcher:127.0.0.1:51455 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1041330 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1041330 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1041331 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1041332 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1041334 INFO (zkCallback-1367-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@50a6fb7 name:ZooKeeperConnection Watcher:127.0.0.1:51455 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1041334 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1041334 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1041334 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1041337 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1041338 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1041339 INFO (zkCallback-1368-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1b8bf074 name:ZooKeeperConnection Watcher:127.0.0.1:51455/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1041339 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1041339 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1041340 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1041342 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1041343 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1041344 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1041345 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1041346 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1041347 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1041348 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader addZkWatch [c1] [junit4] 2> 1041348 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 1041348 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1041348 INFO (zkCallback-1368-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1041349 INFO (zkCallback-1368-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1041350 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1041350 INFO (zkCallback-1368-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0]) [junit4] 2> 1041351 INFO (zkCallback-1368-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1041351 INFO (zkCallback-1368-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1041461 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51455 51455 [junit4] 2> 1041760 INFO (Thread-2525) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51455 51455 [junit4] 2> 1041784 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay [junit4] 2> 1041840 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched [junit4] 2> 1041841 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1041845 INFO (Thread-2526) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1041845 INFO (Thread-2526) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1041946 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer start zk server on port:52372 [junit4] 2> 1041946 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1041947 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1041954 INFO (zkCallback-1369-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f8a3a2e name:ZooKeeperConnection Watcher:127.0.0.1:52372 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1041955 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1041955 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1041956 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1041956 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 0x1543568bfa00000, 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> 1041957 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1041958 INFO (zkCallback-1370-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b969a2d name:ZooKeeperConnection Watcher:127.0.0.1:52372 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1041958 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1041958 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1041958 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1041961 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1041961 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1041962 INFO (zkCallback-1371-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@20297088 name:ZooKeeperConnection Watcher:127.0.0.1:52372/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1041962 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1041962 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1041963 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1042000 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1042001 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1042002 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1042002 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1042003 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1042003 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1042003 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1042004 INFO (zkCallback-1371-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1042004 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1042020 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader addZkWatch [c1] [junit4] 2> 1042021 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1042021 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Removing watch for uninteresting collection [c1] [junit4] 2> 1042033 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52372 52372 [junit4] 2> 1042211 INFO (Thread-2526) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52372 52372 [junit4] 2> 1042216 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched [junit4] 2> 1042218 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation [junit4] 2> 1042219 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1042223 INFO (Thread-2527) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1042223 INFO (Thread-2527) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1042324 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer start zk server on port:45030 [junit4] 2> 1042324 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1042336 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1042346 INFO (zkCallback-1372-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6f2e106b name:ZooKeeperConnection Watcher:127.0.0.1:45030 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1042346 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1042347 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1042348 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1042351 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1042356 INFO (zkCallback-1373-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@57016ffb name:ZooKeeperConnection Watcher:127.0.0.1:45030 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1042356 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1042357 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1042357 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1042358 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1042358 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1042359 INFO (zkCallback-1374-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5b044552 name:ZooKeeperConnection Watcher:127.0.0.1:45030/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1042560 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1042560 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1042563 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1042566 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1042567 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1042568 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1042569 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1042569 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1042570 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1042571 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader addZkWatch [c1] [junit4] 2> 1042571 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 1042571 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1042572 INFO (zkCallback-1374-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1042574 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1042574 INFO (zkCallback-1374-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0]) [junit4] 2> 1042574 INFO (zkCallback-1374-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1042632 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45030 45030 [junit4] 2> 1042916 INFO (Thread-2527) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45030 45030 [junit4] 2> 1042924 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[106C213269B6897F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation [junit4] 2> 1042925 INFO (SUITE-ZkStateReaderTest-seed#[106C213269B6897F]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_106C213269B6897F-001 [junit4] 2> Apr 20, 2016 8:42:18 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=1217, maxMBSortInHeap=4.889808926012612, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=fi, timezone=Atlantic/Faroe [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=180154824,total=516947968 [junit4] 2> NOTE: All tests run in this JVM: [DistributedFacetPivotSmallAdvancedTest, HdfsCollectionsAPIDistributedZkTest, RuleEngineTest, QueryElevationComponentTest, JSONWriterTest, TestAtomicUpdateErrorCases, IndexSchemaTest, TestManagedSynonymFilterFactory, TestSearcherReuse, BasicDistributedZkTest, TestFoldingMultitermQuery, SuggestComponentTest, DeleteStatusTest, TestFieldResource, ResponseLogComponentTest, DeleteLastCustomShardedReplicaTest, PrimUtilsTest, TestSortByMinMaxFunction, TestOmitPositions, ResourceLoaderTest, TestSchemaNameResource, OverseerCollectionConfigSetProcessorTest, TestStressUserVersions, DefaultValueUpdateProcessorTest, CreateCollectionCleanupTest, TestSolrDynamicMBean, TestSolrQueryResponse, HdfsWriteToMultipleCollectionsTest, CloudExitableDirectoryReaderTest, TestPseudoReturnFields, CollectionTooManyReplicasTest, TestRequestForwarding, RequiredFieldsTest, TestLazyCores, AtomicUpdatesTest, TestConfigSets, TestLMDirichletSimilarityFactory, LeaderElectionIntegrationTest, PreAnalyzedUpdateProcessorTest, TestMacros, SystemInfoHandlerTest, SecurityConfHandlerTest, AnalyticsQueryTest, SharedFSAutoReplicaFailoverTest, TestCollectionAPI, TestPhraseSuggestions, RankQueryTest, TestSolr4Spatial2, AsyncCallRequestStatusResponseTest, ConcurrentDeleteAndCreateCollectionTest, HighlighterMaxOffsetTest, TestBinaryField, TestScoreJoinQPNoScore, TestReloadAndDeleteDocs, TestRandomFaceting, TestRealTimeGet, TestSolr4Spatial, DistributedQueryComponentCustomSortTest, ExitableDirectoryReaderTest, AddBlockUpdateTest, RAMDirectoryFactoryTest, TestInfoStreamLogging, BasicZkTest, TestDownShardTolerantSearch, TestPivotHelperCode, XsltUpdateRequestHandlerTest, CloudMLTQParserTest, TestHashQParserPlugin, TestHighlightDedupGrouping, BlockJoinFacetRandomTest, HdfsThreadLeakTest, TestSolrConfigHandlerCloud, TestReRankQParserPlugin, TestDistributedSearch, SolrRequestParserTest, TestTolerantUpdateProcessorCloud, TestSchemaVersionResource, TestPKIAuthenticationPlugin, HdfsBasicDistributedZkTest, TestRandomRequestDistribution, TestComponentsName, TestHdfsUpdateLog, ActionThrottleTest, TestCrossCoreJoin, TestSolrQueryParserResource, ZkStateReaderTest] [junit4] Completed [189/590 (1!)] on J0 in 4.07s, 6 tests, 1 error <<< FAILURES! [...truncated 1292 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:740: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/common-build.xml:529: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:1014: There were test failures: 590 suites (10 ignored), 2373 tests, 1 error, 85 ignored (70 assumptions) [seed: 106C213269B6897F] Total time: 94 minutes 40 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org