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/06/16 01:59:28 UTC
[JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_92) - Build # 288 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/288/
Java: 64bit/jdk1.8.0_92 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
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([B32069276DA55645:AC9A18D0BDC59080]:0)
at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:136)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:42)
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 10737 lines...]
[junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_B32069276DA55645-001/init-core-data-001
[junit4] 2> 54922 INFO (SUITE-ZkStateReaderTest-seed#[B32069276DA55645]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true)
[junit4] 2> 54922 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 54923 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 54924 INFO (Thread-262) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 54924 INFO (Thread-262) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 55024 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer start zk server on port:42768
[junit4] 2> 55024 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55024 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55027 INFO (zkCallback-136-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@58bb952e name:ZooKeeperConnection Watcher:127.0.0.1:42768 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55027 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55027 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55028 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55028 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55031 INFO (zkCallback-137-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5a3233ab name:ZooKeeperConnection Watcher:127.0.0.1:42768 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55031 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55032 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55032 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 55035 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55035 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55037 INFO (zkCallback-138-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@73da3507 name:ZooKeeperConnection Watcher:127.0.0.1:42768/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55037 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55037 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55038 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 55040 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 55056 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 55057 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 55058 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 55058 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 55060 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
[junit4] 2> 55061 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 55061 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 55063 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 55063 INFO (zkCallback-138-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 55064 INFO (zkCallback-138-thread-2) [ ] 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> 55064 INFO (zkCallback-138-thread-2) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 55116 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42768 42768
[junit4] 2> 55278 INFO (Thread-262) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42768 42768
[junit4] 2> 55279 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 55281 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 55281 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 55282 INFO (Thread-263) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 55282 INFO (Thread-263) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 55382 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer start zk server on port:35457
[junit4] 2> 55382 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55386 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55388 INFO (zkCallback-139-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6a969d08 name:ZooKeeperConnection Watcher:127.0.0.1:35457 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55388 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55388 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55390 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55390 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55393 INFO (zkCallback-140-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@44d4fe71 name:ZooKeeperConnection Watcher:127.0.0.1:35457 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55393 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55393 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55393 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 55397 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55398 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55400 INFO (zkCallback-141-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@499c79d5 name:ZooKeeperConnection Watcher:127.0.0.1:35457/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55400 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55401 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55401 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 55402 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 55403 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 55404 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 55404 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 55405 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 55406 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 55408 INFO (zkCallback-141-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> 55408 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 55410 INFO (zkCallback-141-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 55410 INFO (zkCallback-141-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> 55421 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35457 35457
[junit4] 2> 55476 INFO (Thread-263) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35457 35457
[junit4] 2> 55481 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 55485 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 55486 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 55486 INFO (Thread-264) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 55486 INFO (Thread-264) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 55586 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer start zk server on port:41123
[junit4] 2> 55586 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55595 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55631 INFO (zkCallback-142-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@33638d35 name:ZooKeeperConnection Watcher:127.0.0.1:41123 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55631 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55631 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55633 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55643 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55645 INFO (zkCallback-143-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@73697d96 name:ZooKeeperConnection Watcher:127.0.0.1:41123 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55645 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55645 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55645 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 55651 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55652 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55659 INFO (zkCallback-144-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2f513666 name:ZooKeeperConnection Watcher:127.0.0.1:41123/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55659 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55659 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55660 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 55661 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 55661 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 55662 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 55663 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 55663 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 55664 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 55665 INFO (zkCallback-144-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 55667 INFO (zkCallback-144-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> 55718 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 55719 INFO (zkCallback-144-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> 55721 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41123 41123
[junit4] 2> 55771 INFO (Thread-264) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41123 41123
[junit4] 2> 55772 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 55777 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 55777 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 55778 INFO (Thread-265) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 55778 INFO (Thread-265) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 55878 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer start zk server on port:37925
[junit4] 2> 55878 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55878 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55885 INFO (zkCallback-145-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b412aa0 name:ZooKeeperConnection Watcher:127.0.0.1:37925 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55885 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55885 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55886 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55886 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55888 INFO (zkCallback-146-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f6619b8 name:ZooKeeperConnection Watcher:127.0.0.1:37925 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55888 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55888 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55888 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 55902 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 55903 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 55905 INFO (zkCallback-147-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5d1fe61a name:ZooKeeperConnection Watcher:127.0.0.1:37925/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 55905 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55905 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 55906 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 55907 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 55908 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 55909 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 55910 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 55910 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 55912 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 55913 INFO (zkCallback-147-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 55913 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 55933 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
[junit4] 2> 55934 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 55934 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader Removing watch for uninteresting collection [c1]
[junit4] 2> 57436 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37925 37925
[junit4] 2> 57519 INFO (Thread-265) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37925 37925
[junit4] 2> 57520 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 57532 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 57532 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 57533 INFO (Thread-266) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 57533 INFO (Thread-266) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 57633 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer start zk server on port:41798
[junit4] 2> 57633 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 57633 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 57636 INFO (zkCallback-148-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b2b69e7 name:ZooKeeperConnection Watcher:127.0.0.1:41798 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 57636 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 57636 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 57644 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 57645 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 57646 INFO (zkCallback-149-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2b0e4d4d name:ZooKeeperConnection Watcher:127.0.0.1:41798 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 57646 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 57646 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 57646 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 57655 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 57655 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 57658 INFO (zkCallback-150-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6d23999a name:ZooKeeperConnection Watcher:127.0.0.1:41798/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 57658 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 57658 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 57660 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 57661 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 57662 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 57663 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 57664 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 57664 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 57665 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
[junit4] 2> 57667 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 57667 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 57671 INFO (zkCallback-150-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 57675 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 57678 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]-EventThread) [ ] o.a.z.ClientCnxn Error while calling watcher
[junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1@1bb22457 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@1f8da3f9[Shutting down, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 2]
[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:261)
[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> 57679 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41798 41798
[junit4] 2> 57679 ERROR (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]-EventThread) [ ] o.a.z.ClientCnxn Error while calling watcher
[junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1@61859434 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@1f8da3f9[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 2]
[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:261)
[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> 57793 INFO (Thread-266) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41798 41798
[junit4] 2> 57794 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithExplicitRefresh -Dtests.seed=B32069276DA55645 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es-PR -Dtests.timezone=Europe/Belgrade -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.28s J2 | ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
[junit4] > at __randomizedtesting.SeedInfo.seed([B32069276DA55645:AC9A18D0BDC59080]:0)
[junit4] > at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:136)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:42)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 57806 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 57807 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 57807 INFO (Thread-267) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 57807 INFO (Thread-267) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 57907 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer start zk server on port:41950
[junit4] 2> 57908 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 57908 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 57910 INFO (zkCallback-151-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@234e23d2 name:ZooKeeperConnection Watcher:127.0.0.1:41950 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 57910 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 57910 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 57912 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 57912 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 57913 INFO (zkCallback-152-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@23d47842 name:ZooKeeperConnection Watcher:127.0.0.1:41950 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 57913 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 57913 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 57913 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 57929 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 57930 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 57938 INFO (zkCallback-153-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@438a9097 name:ZooKeeperConnection Watcher:127.0.0.1:41950/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 57938 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 57938 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 57939 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 57940 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 57941 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 57942 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 57943 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 57944 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 57945 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
[junit4] 2> 57945 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 57945 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 57946 INFO (zkCallback-153-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 57947 INFO (zkCallback-153-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> 57948 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 57948 INFO (zkCallback-153-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> 57949 INFO (zkCallback-153-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> 57949 INFO (zkCallback-153-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 57967 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41950 41950
[junit4] 2> 58105 INFO (Thread-267) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41950 41950
[junit4] 2> 58107 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[B32069276DA55645]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> 58108 INFO (SUITE-ZkStateReaderTest-seed#[B32069276DA55645]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_B32069276DA55645-001
[junit4] 2> Jun 16, 2016 1:29:11 AM 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(Lucene54): {}, docValues:{}, sim=DefaultSimilarity, locale=es-PR, timezone=Europe/Belgrade
[junit4] 2> NOTE: Linux 4.4.0-24-generic amd64/Oracle Corporation 1.8.0_92 (64-bit)/cpus=12,threads=1,free=327300656,total=508887040
[junit4] 2> NOTE: All tests run in this JVM: [SuggesterWFSTTest, AtomicUpdatesTest, BlockDirectoryTest, DocValuesMissingTest, RequestHandlersTest, TestJettySolrRunner, DistributedFacetPivotSmallTest, TestSolrIndexConfig, StatelessScriptUpdateProcessorFactoryTest, TestElisionMultitermQuery, TestZkChroot, CursorMarkTest, TestSolrConfigHandlerConcurrent, ZkStateReaderTest]
[junit4] Completed [35/572 (1!)] on J2 in 3.95s, 6 tests, 1 error <<< FAILURES!
[...truncated 1692 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:750: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:694: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/common-build.xml:534: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1477: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1033: There were test failures: 572 suites (7 ignored), 2303 tests, 1 error, 84 ignored (39 assumptions) [seed: B32069276DA55645]
Total time: 50 minutes 33 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any