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/13 05:40:39 UTC
[JENKINS] Lucene-Solr-6.x-Linux (64bit/jdk1.8.0_92) - Build # 885 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/885/
Java: 64bit/jdk1.8.0_92 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay
Error Message:
Could not find collection : c1
Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : c1
at __randomizedtesting.SeedInfo.seed([C782C1C928F048C8:B81C764C41926542]:0)
at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:130)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:52)
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 12155 lines...]
[junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_C782C1C928F048C8-001/init-core-data-001
[junit4] 2> 1747261 INFO (SUITE-ZkStateReaderTest-seed#[C782C1C928F048C8]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1747262 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 1747274 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1747274 INFO (Thread-4569) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1747274 INFO (Thread-4569) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1747375 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer start zk server on port:34561
[junit4] 2> 1747375 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747376 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747377 INFO (zkCallback-2703-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2c796f43 name:ZooKeeperConnection Watcher:127.0.0.1:34561 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747377 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747378 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747379 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747379 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747380 INFO (zkCallback-2704-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2967bb6f name:ZooKeeperConnection Watcher:127.0.0.1:34561 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747380 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747380 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747380 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1747382 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 0x155484293500001, 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> 1747383 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747384 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747384 INFO (zkCallback-2705-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f207ad0 name:ZooKeeperConnection Watcher:127.0.0.1:34561/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747384 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747384 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747385 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1747385 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1747387 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1747388 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1747388 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1747389 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1747389 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1747389 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1747390 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1747391 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1747392 INFO (zkCallback-2705-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> 1747392 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1747393 INFO (zkCallback-2705-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1747397 INFO (zkCallback-2705-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> 1747397 INFO (zkCallback-2705-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> 1747400 INFO (zkCallback-2705-thread-2) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1747401 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34561 34561
[junit4] 2> 1747443 INFO (Thread-4569) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34561 34561
[junit4] 2> 1747444 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=C782C1C928F048C8 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fi -Dtests.timezone=PRT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.19s J2 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
[junit4] > at __randomizedtesting.SeedInfo.seed([C782C1C928F048C8:B81C764C41926542]:0)
[junit4] > at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:130)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:52)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1747448 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 1747449 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1747449 INFO (Thread-4570) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1747449 INFO (Thread-4570) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1747549 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer start zk server on port:45635
[junit4] 2> 1747549 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747550 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747555 INFO (zkCallback-2707-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@12753cc9 name:ZooKeeperConnection Watcher:127.0.0.1:45635 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747555 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747556 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747556 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747556 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747557 INFO (zkCallback-2708-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6deb372c name:ZooKeeperConnection Watcher:127.0.0.1:45635 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747557 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747558 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747558 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1747559 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747559 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747560 INFO (zkCallback-2709-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@18f25dab name:ZooKeeperConnection Watcher:127.0.0.1:45635/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747560 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747560 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747561 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1747561 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1747562 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1747562 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1747563 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1747563 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1747564 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1747564 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1747565 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1747565 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1747568 INFO (zkCallback-2709-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1747568 INFO (zkCallback-2709-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> 1747570 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1747570 INFO (zkCallback-2709-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> 1747570 INFO (zkCallback-2709-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> 1747571 INFO (zkCallback-2709-thread-2) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1747572 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45635 45635
[junit4] 2> 1747600 INFO (Thread-4570) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45635 45635
[junit4] 2> 1747601 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 1747605 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 1747606 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1747606 INFO (Thread-4571) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1747606 INFO (Thread-4571) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1747706 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer start zk server on port:45397
[junit4] 2> 1747706 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747707 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747708 INFO (zkCallback-2711-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@69936d51 name:ZooKeeperConnection Watcher:127.0.0.1:45397 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747708 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747708 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747709 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747709 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747711 INFO (zkCallback-2712-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5f28bc65 name:ZooKeeperConnection Watcher:127.0.0.1:45397 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747711 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747712 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747712 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1747713 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747713 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747714 INFO (zkCallback-2713-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@11f72622 name:ZooKeeperConnection Watcher:127.0.0.1:45397/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747714 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747714 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747714 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1747716 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1747717 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1747717 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1747718 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1747719 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1747719 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1747720 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1747721 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1747721 INFO (zkCallback-2713-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1747724 INFO (zkCallback-2713-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> 1747725 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1747725 INFO (zkCallback-2713-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> 1747727 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45397 45397
[junit4] 2> 1747825 INFO (Thread-4571) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45397 45397
[junit4] 2> 1747825 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 1747827 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 1747827 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1747827 INFO (Thread-4572) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1747827 INFO (Thread-4572) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1747927 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer start zk server on port:38886
[junit4] 2> 1747927 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747928 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747929 INFO (zkCallback-2715-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2ed7df68 name:ZooKeeperConnection Watcher:127.0.0.1:38886 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747929 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747929 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747930 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747930 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747931 INFO (zkCallback-2716-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@41de2fbd name:ZooKeeperConnection Watcher:127.0.0.1:38886 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747931 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747931 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747931 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1747933 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1747933 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1747934 INFO (zkCallback-2717-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@67046cc0 name:ZooKeeperConnection Watcher:127.0.0.1:38886/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1747934 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1747934 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1747934 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1747935 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1747936 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1747936 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1747937 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1747937 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1747939 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1747940 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1747940 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1747940 INFO (zkCallback-2717-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1747941 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1747941 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1747942 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38886 38886
[junit4] 2> 1747949 INFO (Thread-4572) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:38886 38886
[junit4] 2> 1747949 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 1747953 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 1747953 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1747953 INFO (Thread-4573) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1747953 INFO (Thread-4573) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1748053 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer start zk server on port:41357
[junit4] 2> 1748053 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1748054 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1748055 INFO (zkCallback-2719-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@754d8a70 name:ZooKeeperConnection Watcher:127.0.0.1:41357 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1748055 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1748055 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1748056 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1748056 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1748056 INFO (zkCallback-2720-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@8856909 name:ZooKeeperConnection Watcher:127.0.0.1:41357 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1748056 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1748057 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1748057 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1748059 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1748059 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1748060 INFO (zkCallback-2721-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@297d6a30 name:ZooKeeperConnection Watcher:127.0.0.1:41357/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1748060 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1748060 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1748060 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1748061 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1748061 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1748062 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1748063 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1748063 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1748063 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1748063 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1748064 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1748064 INFO (zkCallback-2721-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1748065 INFO (zkCallback-2721-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> 1748115 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1748116 INFO (zkCallback-2721-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> 1748117 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41357 41357
[junit4] 2> 1748208 INFO (Thread-4573) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41357 41357
[junit4] 2> 1748208 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 1748210 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 1748211 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1748211 INFO (Thread-4574) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1748211 INFO (Thread-4574) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1748311 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer start zk server on port:41296
[junit4] 2> 1748312 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1748312 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1748313 INFO (zkCallback-2723-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@75759e0a name:ZooKeeperConnection Watcher:127.0.0.1:41296 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1748313 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1748314 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1748315 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1748315 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1748316 INFO (zkCallback-2724-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@45ab2fe4 name:ZooKeeperConnection Watcher:127.0.0.1:41296 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1748316 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1748316 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1748316 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1748318 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1748318 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1748319 INFO (zkCallback-2725-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3fe3a88 name:ZooKeeperConnection Watcher:127.0.0.1:41296/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1748319 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1748319 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1748319 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1748320 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1748320 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1748320 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1748321 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1748321 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1748321 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1748323 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1748324 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1748324 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1748324 INFO (zkCallback-2725-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1748325 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1748325 INFO (zkCallback-2725-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> 1748325 INFO (zkCallback-2725-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1748377 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 0x155484296fa0002, 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> 1748378 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41296 41296
[junit4] 2> 1748472 INFO (Thread-4574) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41296 41296
[junit4] 2> 1748473 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[C782C1C928F048C8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 1748473 INFO (SUITE-ZkStateReaderTest-seed#[C782C1C928F048C8]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_C782C1C928F048C8-001
[junit4] 2> Jun 13, 2016 5:36:11 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 5 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=fi, timezone=PRT
[junit4] 2> NOTE: Linux 4.4.0-24-generic amd64/Oracle Corporation 1.8.0_92 (64-bit)/cpus=12,threads=1,free=136433952,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [ScriptEngineTest, TestOrdValues, CollectionStateFormat2Test, TestXIncludeConfig, RemoteQueryErrorTest, BadIndexSchemaTest, TestMaxScoreQueryParser, NoCacheHeaderTest, StressHdfsTest, RegexBoostProcessorTest, BasicDistributedZk2Test, FullSolrCloudDistribCmdsTest, TestOverriddenPrefixQueryForCustomFieldType, HdfsTlogReplayBufferedWhileIndexingTest, TestJettySolrRunner, TestGroupingSearch, TestUtils, ConjunctionSolrSpellCheckerTest, TestReloadDeadlock, TestCharFilters, TestIndexSearcher, TestFieldTypeCollectionResource, TestReload, CoreMergeIndexesAdminHandlerTest, SimpleFacetsTest, DeleteInactiveReplicaTest, RecoveryZkTest, CurrencyFieldXmlFileTest, CollectionsAPISolrJTest, TestWriterPerf, DateMathParserTest, FullHLLTest, TestIntervalFaceting, TestNamedUpdateProcessors, TestXmlQParser, TestSolrQueryParser, UnloadDistributedZkTest, TestRequestForwarding, TestSuggestSpellingConverter, TestSolrDeletionPolicy1, TestFieldCache, TestSearchPerf, TestZkChroot, BlockCacheTest, HdfsNNFailoverTest, TestCSVResponseWriter, TestRestoreCore, TestManagedSchema, HdfsDirectoryTest, SuggesterWFSTTest, TestRuleBasedAuthorizationPlugin, TestPerFieldSimilarity, TestNumericTerms64, RollingRestartTest, DistributedQueryComponentOptimizationTest, TestReversedWildcardFilterFactory, TestIndexingPerformance, TestSolrIndexConfig, TestJmxMonitoredMap, TestSolrXml, BaseCdcrDistributedZkTest, GraphQueryTest, TestConfigReload, AnalysisErrorHandlingTest, TestClassicSimilarityFactory, VMParamsZkACLAndCredentialsProvidersTest, TestSort, HdfsChaosMonkeyNothingIsSafeTest, StatsComponentTest, TestFileDictionaryLookup, CleanupOldIndexTest, SuggesterTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, DebugComponentTest, ClassificationUpdateProcessorFactoryTest, OverseerTest, TestDocBasedVersionConstraints, BigEndianAscendingWordSerializerTest, DistributedFacetPivotLargeTest, RankQueryTest, TestRecovery, TestStressRecovery, TestStandardQParsers, FieldMutatingUpdateProcessorTest, TestDynamicLoading, TestShardHandlerFactory, TestDefaultStatsCache, LeaderElectionTest, TestGraphTermsQParserPlugin, LukeRequestHandlerTest, PluginInfoTest, TestCustomSort, PKIAuthenticationIntegrationTest, TestSlowCompositeReaderWrapper, TestTolerantSearch, TestSolrCLIRunExample, OpenCloseCoreStressTest, TestReplicationHandler, AutoCommitTest, TermVectorComponentDistributedTest, HardAutoCommitTest, TestSolr4Spatial, SolrCoreTest, ConvertedLegacyTest, TestFunctionQuery, SoftAutoCommitTest, PolyFieldTest, SolrCoreCheckLockOnStartupTest, TestWordDelimiterFilterFactory, StatelessScriptUpdateProcessorFactoryTest, DirectSolrSpellCheckerTest, XmlUpdateRequestHandlerTest, TestValueSourceCache, MoreLikeThisHandlerTest, FastVectorHighlighterTest, SolrPluginUtilsTest, QueryParsingTest, TestPHPSerializedResponseWriter, HighlighterConfigTest, SOLR749Test, UpdateParamsTest, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, MultiTermTest, SampleTest, TestBinaryField, NumericFieldsTest, TestElisionMultitermQuery, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestPostingsSolrHighlighter, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestPhraseSuggestions, TestCodecSupport, SynonymTokenizerTest, TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory, TestBM25SimilarityFactory, TestFastWriter, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, CloudExitableDirectoryReaderTest, CreateCollectionCleanupTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, DeleteStatusTest, DistribDocExpirationUpdateProcessorTest, MigrateRouteKeyTest, OverseerStatusTest, RecoveryAfterSoftCommitTest, SolrCloudExampleTest, TestCloudBackupRestore, TestCloudDeleteByQuery, TestCloudInspectUtil, TestCloudPivotFacet, TestConfigSetsAPI, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDistribDocBasedVersion, TestLeaderInitiatedRecoveryThread, TestMiniSolrCloudClusterSSL, TestRebalanceLeaders, TestTolerantUpdateProcessorRandomCloud, HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest, HdfsCollectionsAPIDistributedZkTest, HdfsRecoveryZkTest, HdfsRestartWhileUpdatingTest, HdfsSyncSliceTest, HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, TestClusterStateMutator, ZkStateReaderTest]
[junit4] Completed [499/615 (1!)] on J2 in 1.48s, 6 tests, 1 error <<< FAILURES!
[...truncated 389 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:740: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:684: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/common-build.xml:531: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:1427: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/lucene/common-build.xml:984: There were test failures: 615 suites (10 ignored), 2590 tests, 1 error, 116 ignored (72 assumptions) [seed: C782C1C928F048C8]
Total time: 55 minutes 47 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