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/07/08 08:41:15 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (32bit/jdk-9-ea+125) - Build
# 17185 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17185/
Java: 32bit/jdk-9-ea+125 -server -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([6F0B43B6F6D42519:70B1324126B4E3DC]:0)
at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:43)
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:533)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
Build Log:
[...truncated 10855 lines...]
[junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_6F0B43B6F6D42519-001/init-core-data-001
[junit4] 2> 269101 INFO (SUITE-ZkStateReaderTest-seed#[6F0B43B6F6D42519]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 269103 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 269103 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 269103 INFO (Thread-709) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 269103 INFO (Thread-709) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 269203 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer start zk server on port:37431
[junit4] 2> 269204 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269204 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269205 INFO (zkCallback-320-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1aede1 name:ZooKeeperConnection Watcher:127.0.0.1:37431 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269205 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269206 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269206 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269207 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269207 INFO (zkCallback-321-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f92a16 name:ZooKeeperConnection Watcher:127.0.0.1:37431 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269207 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269208 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269208 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 269209 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269210 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269210 INFO (zkCallback-322-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f99269 name:ZooKeeperConnection Watcher:127.0.0.1:37431/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269210 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269210 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269211 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 269211 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 269211 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 269212 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 269212 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 269213 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 269213 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 269213 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 269214 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 269214 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 269214 INFO (zkCallback-322-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 269215 INFO (zkCallback-322-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> 269218 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 269218 INFO (zkCallback-322-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> 269218 INFO (zkCallback-322-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 269219 INFO (zkCallback-322-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> 269220 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37431 37431
[junit4] 2> 269239 INFO (Thread-709) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37431 37431
[junit4] 2> 269239 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> 269241 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 269241 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 269241 INFO (Thread-710) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 269241 INFO (Thread-710) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 269342 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer start zk server on port:43967
[junit4] 2> 269342 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269343 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269344 INFO (zkCallback-324-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1e40eb2 name:ZooKeeperConnection Watcher:127.0.0.1:43967 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269344 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269344 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269345 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269345 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269346 INFO (zkCallback-325-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1c2aa7a name:ZooKeeperConnection Watcher:127.0.0.1:43967 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269346 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269346 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269346 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 269351 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269352 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269354 INFO (zkCallback-326-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1732954 name:ZooKeeperConnection Watcher:127.0.0.1:43967/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269354 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269354 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269354 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 269355 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 269356 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 269356 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 269357 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 269357 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 269358 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 269358 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 269359 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 269359 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 269360 INFO (zkCallback-326-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 269360 INFO (zkCallback-326-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> 269361 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 269361 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 269362 INFO (zkCallback-326-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> 269362 INFO (zkCallback-326-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> 269362 INFO (zkCallback-326-thread-2) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 269363 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 269367 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43967 43967
[junit4] 2> 269391 INFO (Thread-710) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43967 43967
[junit4] 2> 269392 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithExplicitRefresh -Dtests.seed=6F0B43B6F6D42519 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=haw -Dtests.timezone=Europe/Vienna -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.16s J2 | ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
[junit4] > at __randomizedtesting.SeedInfo.seed([6F0B43B6F6D42519:70B1324126B4E3DC]:0)
[junit4] > at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:192)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
[junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:43)
[junit4] > at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
[junit4] > at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
[junit4] > at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
[junit4] 2> 269399 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 269400 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 269400 INFO (Thread-711) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 269400 INFO (Thread-711) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 269500 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer start zk server on port:37428
[junit4] 2> 269500 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269501 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269502 INFO (zkCallback-328-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@aa46b6 name:ZooKeeperConnection Watcher:127.0.0.1:37428 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269502 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269503 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269504 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269505 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269505 INFO (zkCallback-329-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@11ee68f name:ZooKeeperConnection Watcher:127.0.0.1:37428 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269505 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269506 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269506 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 269508 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269508 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269509 INFO (zkCallback-330-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@573980 name:ZooKeeperConnection Watcher:127.0.0.1:37428/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269509 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269509 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269509 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 269510 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 269511 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 269511 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 269512 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 269513 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 269513 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 269513 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 269514 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 269515 INFO (zkCallback-330-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 269531 INFO (zkCallback-330-thread-1) [ ] o.a.s.c.c.ZkStateReader Adding lazy collectionRef for collection c1
[junit4] 2> 269531 INFO (zkCallback-330-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> 269532 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 269532 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 269533 INFO (zkCallback-330-thread-1) [ ] o.a.s.c.c.ZkStateReader Uninteresting collection c1
[junit4] 2> 269533 INFO (zkCallback-330-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> 269534 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 269535 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37428 37428
[junit4] 2> 269683 INFO (Thread-711) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37428 37428
[junit4] 2> 269683 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 269686 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 269686 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 269697 INFO (Thread-712) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 269697 INFO (Thread-712) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 269797 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer start zk server on port:42220
[junit4] 2> 269797 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269798 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269799 INFO (zkCallback-332-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@19f63b4 name:ZooKeeperConnection Watcher:127.0.0.1:42220 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269799 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269800 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269800 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269801 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269802 INFO (zkCallback-333-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@34602d name:ZooKeeperConnection Watcher:127.0.0.1:42220 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269802 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269802 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269802 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 269803 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 269804 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 269837 INFO (zkCallback-334-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9203bb name:ZooKeeperConnection Watcher:127.0.0.1:42220/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 269837 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 269837 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 269837 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 269838 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 269839 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 269840 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 269841 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 269841 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 269841 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 269842 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 269843 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 269844 INFO (zkCallback-334-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 269844 INFO (zkCallback-334-thread-1) [ ] o.a.s.c.c.ZkStateReader Adding lazy collectionRef for collection c1
[junit4] 2> 269867 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Refreshing lazily-loaded state for collection c1
[junit4] 2> 269868 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 269869 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 269878 INFO (zkCallback-334-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> 269880 WARN (zkCallback-334-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /clusterstate.json]
[junit4] 2> 269881 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42220 42220
[junit4] 2> 270030 INFO (Thread-712) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42220 42220
[junit4] 2> 270033 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 270034 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 270035 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 270035 INFO (Thread-713) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 270035 INFO (Thread-713) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 270135 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer start zk server on port:39265
[junit4] 2> 270135 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 270136 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 270146 INFO (zkCallback-336-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@787474 name:ZooKeeperConnection Watcher:127.0.0.1:39265 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 270146 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 270147 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 270148 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 270149 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 270149 INFO (zkCallback-337-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@159ad52 name:ZooKeeperConnection Watcher:127.0.0.1:39265 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 270149 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 270149 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 270149 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 270150 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 270151 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 270151 INFO (zkCallback-338-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1770572 name:ZooKeeperConnection Watcher:127.0.0.1:39265/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 270151 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 270152 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 270152 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 270153 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 270153 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 270154 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 270155 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 270156 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 270156 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 270156 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 270158 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 270158 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 270158 INFO (zkCallback-338-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 270159 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 270159 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 270160 INFO (zkCallback-338-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> 270160 INFO (zkCallback-338-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 270163 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39265 39265
[junit4] 2> 270384 INFO (Thread-713) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39265 39265
[junit4] 2> 270384 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 270390 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 270390 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 270390 INFO (Thread-714) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 270390 INFO (Thread-714) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 270491 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer start zk server on port:46748
[junit4] 2> 270491 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 270492 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 270493 INFO (zkCallback-340-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@152f686 name:ZooKeeperConnection Watcher:127.0.0.1:46748 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 270493 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 270494 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 270494 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 270496 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 270496 INFO (zkCallback-341-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@17f742b name:ZooKeeperConnection Watcher:127.0.0.1:46748 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 270496 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 270496 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 270496 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 270497 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 270498 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 270499 INFO (zkCallback-342-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b617d4 name:ZooKeeperConnection Watcher:127.0.0.1:46748/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 270499 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 270499 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 270499 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 270500 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 270500 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 270501 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 270501 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 270502 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 270502 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 270502 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 270504 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 270504 INFO (zkCallback-342-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 270504 INFO (zkCallback-342-thread-1) [ ] o.a.s.c.c.ZkStateReader Adding lazy collectionRef for collection c1
[junit4] 2> 270505 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 270505 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Refreshing lazily-loaded state for collection c1
[junit4] 2> 270505 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 270506 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46748 46748
[junit4] 2> 270621 INFO (Thread-714) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46748 46748
[junit4] 2> 270622 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[6F0B43B6F6D42519]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 270623 INFO (SUITE-ZkStateReaderTest-seed#[6F0B43B6F6D42519]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.overseer.ZkStateReaderTest_6F0B43B6F6D42519-001
[junit4] 2> Jul 08, 2016 8:12:05 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(Lucene62): {}, docValues:{}, maxPointsInLeafNode=658, maxMBSortInHeap=7.017015207732694, sim=ClassicSimilarity, locale=haw, timezone=Europe/Vienna
[junit4] 2> NOTE: Linux 4.4.0-28-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=56423408,total=223023104
[junit4] 2> NOTE: All tests run in this JVM: [BaseCdcrDistributedZkTest, SpatialRPTFieldTypeTest, TestFieldTypeCollectionResource, TestSolrJ, SampleTest, TestIndexSearcher, TestReloadDeadlock, DistributedFacetPivotSmallTest, HdfsLockFactoryTest, DistributedFacetPivotLargeTest, DirectUpdateHandlerTest, SliceStateTest, TestFastWriter, TestOrdValues, TestPerFieldSimilarityWithDefaultOverride, TestSweetSpotSimilarityFactory, TestOnReconnectListenerSupport, UpdateParamsTest, DateMathParserTest, TestMaxScoreQueryParser, TestHdfsCloudBackupRestore, TolerantUpdateProcessorTest, TestChildDocTransformer, TestExtendedDismaxParser, TestZkChroot, CollectionsAPISolrJTest, TestElisionMultitermQuery, TestCharFilters, FullSolrCloudDistribCmdsTest, MigrateRouteKeyTest, ZkStateReaderTest]
[junit4] Completed [103/620 (1!)] on J2 in 2.04s, 6 tests, 1 error <<< FAILURES!
[...truncated 1724 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:740: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:684: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/common-build.xml:530: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1443: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:984: There were test failures: 620 suites (10 ignored), 2635 tests, 1 error, 136 ignored (88 assumptions) [seed: 6F0B43B6F6D42519]
Total time: 63 minutes 39 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