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/05 14:32:56 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (64bit/jdk-9-ea+125) - Build
# 17157 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17157/
Java: 64bit/jdk-9-ea+125 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh
Error Message:
Could not find collection : c1
Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : c1
at __randomizedtesting.SeedInfo.seed([76EADFCEF23809A6:6950AE392258CF63]: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 10619 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/J0/temp/solr.cloud.overseer.ZkStateReaderTest_76EADFCEF23809A6-001/init-core-data-001
[junit4] 2> 61830 INFO (SUITE-ZkStateReaderTest-seed#[76EADFCEF23809A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 61831 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 61831 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 61831 INFO (Thread-92) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 61831 INFO (Thread-92) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 61931 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer start zk server on port:35852
[junit4] 2> 61932 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 61932 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 61934 INFO (zkCallback-85-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@464818bd name:ZooKeeperConnection Watcher:127.0.0.1:35852 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 61934 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 61935 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 61935 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 61936 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 61936 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9a25e0c name:ZooKeeperConnection Watcher:127.0.0.1:35852 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 61937 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 61937 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 61937 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 61939 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 61939 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 61940 INFO (zkCallback-87-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@17ab386f name:ZooKeeperConnection Watcher:127.0.0.1:35852/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 61940 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 61940 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 61940 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 61941 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 61942 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 61942 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 61943 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 61944 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 61944 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 61944 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 61945 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 61946 INFO (zkCallback-87-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 61947 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 61947 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Refreshing lazily-loaded state for collection c1
[junit4] 2> 61948 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 61949 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35852 35852
[junit4] 2> 62067 INFO (Thread-92) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35852 35852
[junit4] 2> 62067 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 62069 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 62069 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 62069 INFO (Thread-93) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 62069 INFO (Thread-93) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 62169 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer start zk server on port:40664
[junit4] 2> 62170 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 62170 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 62172 INFO (zkCallback-89-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@25448f26 name:ZooKeeperConnection Watcher:127.0.0.1:40664 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 62172 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 62176 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 62177 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 62177 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 62190 INFO (zkCallback-90-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6940ba82 name:ZooKeeperConnection Watcher:127.0.0.1:40664 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 62190 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 62190 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 62190 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 62204 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 62216 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 62218 INFO (zkCallback-91-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2585c855 name:ZooKeeperConnection Watcher:127.0.0.1:40664/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 62218 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 62219 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 62219 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 62220 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 62220 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 62221 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 62222 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 62223 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 62223 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 62224 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 62224 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 62225 INFO (zkCallback-91-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 62228 INFO (zkCallback-91-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> 62233 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 62242 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 62243 INFO (zkCallback-91-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> 62243 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Refreshing lazily-loaded state for collection c1
[junit4] 2> 62247 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40664 40664
[junit4] 2> 67359 INFO (Thread-93) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40664 40664
[junit4] 2> 67360 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 67362 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 67362 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 67362 INFO (Thread-94) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 67362 INFO (Thread-94) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 67462 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer start zk server on port:41297
[junit4] 2> 67462 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 67466 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 67468 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@483b3667 name:ZooKeeperConnection Watcher:127.0.0.1:41297 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 67468 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 67469 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 67470 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 67470 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 67471 INFO (zkCallback-94-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7b74ccb0 name:ZooKeeperConnection Watcher:127.0.0.1:41297 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 67472 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 67472 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 67472 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 67473 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 67474 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 67475 INFO (zkCallback-95-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7fb520c9 name:ZooKeeperConnection Watcher:127.0.0.1:41297/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 67475 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 67475 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 67476 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 67477 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 67478 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 67479 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 67480 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 67481 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 67481 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 67481 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 67482 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 67482 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 67483 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 67484 INFO (zkCallback-95-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 67484 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 67485 INFO (zkCallback-95-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> 67485 INFO (zkCallback-95-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 67493 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41297 41297
[junit4] 2> 67659 INFO (Thread-94) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41297 41297
[junit4] 2> 67661 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 67663 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 67663 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 67663 INFO (Thread-95) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 67663 INFO (Thread-95) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 67763 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer start zk server on port:35987
[junit4] 2> 67763 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 67764 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 67766 INFO (zkCallback-97-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@14917001 name:ZooKeeperConnection Watcher:127.0.0.1:35987 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 67766 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 67767 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 67768 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 67770 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 67772 INFO (zkCallback-98-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a703115 name:ZooKeeperConnection Watcher:127.0.0.1:35987 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 67772 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 67773 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 67773 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 67776 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 67777 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 67778 INFO (zkCallback-99-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b7dac39 name:ZooKeeperConnection Watcher:127.0.0.1:35987/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 67778 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 67779 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 67779 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 67780 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 67783 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 67783 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 67784 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 67784 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 67785 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 67785 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 67786 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 67786 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 67787 INFO (zkCallback-99-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 67787 INFO (zkCallback-99-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> 67788 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 67788 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 67789 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 67791 INFO (zkCallback-99-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> 67791 INFO (zkCallback-99-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> 69291 WARN (zkCallback-99-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> 69292 WARN (zkCallback-99-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /collections/c1/state.json]
[junit4] 2> 69292 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35987 35987
[junit4] 2> 69397 INFO (Thread-95) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35987 35987
[junit4] 2> 69397 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithExplicitRefresh -Dtests.seed=76EADFCEF23809A6 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-SC -Dtests.timezone=Africa/Luanda -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 1.74s J0 | ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
[junit4] > at __randomizedtesting.SeedInfo.seed([76EADFCEF23809A6:6950AE392258CF63]: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> 69401 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 69402 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 69402 INFO (Thread-96) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 69402 INFO (Thread-96) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 69502 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer start zk server on port:37716
[junit4] 2> 69502 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 69502 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 69504 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4ad8f472 name:ZooKeeperConnection Watcher:127.0.0.1:37716 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 69504 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 69504 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 69505 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 69505 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 69506 INFO (zkCallback-102-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4a6d002e name:ZooKeeperConnection Watcher:127.0.0.1:37716 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 69506 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 69506 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 69506 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 69507 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 69507 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 69512 INFO (zkCallback-103-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@59529b96 name:ZooKeeperConnection Watcher:127.0.0.1:37716/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 69512 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 69513 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 69513 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 69514 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 69514 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 69515 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 69515 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 69516 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 69516 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 69516 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 69517 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 69518 INFO (zkCallback-103-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 69519 INFO (zkCallback-103-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> 69520 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 69520 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 69520 INFO (zkCallback-103-thread-1) [ ] o.a.s.c.c.ZkStateReader Uninteresting collection c1
[junit4] 2> 69521 INFO (zkCallback-103-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> 69521 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 69522 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37716 37716
[junit4] 2> 69538 INFO (Thread-96) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37716 37716
[junit4] 2> 69539 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 69540 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 69541 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 69541 INFO (Thread-97) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 69541 INFO (Thread-97) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 69641 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer start zk server on port:45962
[junit4] 2> 69641 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 69641 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 69643 INFO (zkCallback-105-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@79c93f78 name:ZooKeeperConnection Watcher:127.0.0.1:45962 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 69643 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 69643 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 69644 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 69644 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 69645 INFO (zkCallback-106-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@100b1369 name:ZooKeeperConnection Watcher:127.0.0.1:45962 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 69645 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 69645 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 69645 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 69647 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 69647 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 69648 INFO (zkCallback-107-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2e1749b8 name:ZooKeeperConnection Watcher:127.0.0.1:45962/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 69648 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 69648 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 69648 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 69649 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 69650 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 69651 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 69651 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 69652 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 69652 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 69652 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 69653 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 69653 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 69653 INFO (zkCallback-107-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 69654 INFO (zkCallback-107-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> 69654 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 69654 INFO (zkCallback-107-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> 69655 INFO (zkCallback-107-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 69655 INFO (zkCallback-107-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> 69656 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45962 45962
[junit4] 2> 69768 INFO (Thread-97) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45962 45962
[junit4] 2> 69768 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[76EADFCEF23809A6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> 69769 INFO (SUITE-ZkStateReaderTest-seed#[76EADFCEF23809A6]-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/J0/temp/solr.cloud.overseer.ZkStateReaderTest_76EADFCEF23809A6-001
[junit4] 2> Jul 05, 2016 2:04:02 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=12785, maxDocsPerChunk=9, blockSize=162), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=12785, blockSize=162)), sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=fr-SC, timezone=Africa/Luanda
[junit4] 2> NOTE: Linux 4.4.0-28-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=438241056,total=508887040
[junit4] 2> NOTE: All tests run in this JVM: [TestAnalyzeInfixSuggestions, ActionThrottleTest, URLClassifyProcessorTest, TestReloadDeadlock, TestDynamicFieldResource, TestValueSourceCache, LeaderElectionIntegrationTest, TestRemoteStreaming, SystemInfoHandlerTest, TestComponentsName, TestFunctionQuery, TestSerializedLuceneMatchVersion, HdfsThreadLeakTest, TestImplicitCoreProperties, ZkStateReaderTest]
[junit4] Completed [27/618 (1!)] on J0 in 8.97s, 6 tests, 1 error <<< FAILURES!
[...truncated 1912 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:1427: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:984: There were test failures: 618 suites (10 ignored), 2588 tests, 1 error, 117 ignored (70 assumptions) [seed: 76EADFCEF23809A6]
Total time: 52 minutes 44 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
[JENKINS-EA] Lucene-Solr-master-Linux (32bit/jdk-9-ea+125) - Build
# 17158 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17158/
Java: 32bit/jdk-9-ea+125 -server -XX:+UseG1GC
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([692FAEE60999ED4F:7695DF11D9F92B8A]: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 10550 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/J0/temp/solr.cloud.overseer.ZkStateReaderTest_692FAEE60999ED4F-001/init-core-data-001
[junit4] 2> 0 INFO (SUITE-ZkStateReaderTest-seed#[692FAEE60999ED4F]-worker) [ ] o.e.j.u.log Logging initialized @2128ms
[junit4] 2> 5 INFO (SUITE-ZkStateReaderTest-seed#[692FAEE60999ED4F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 33 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
[junit4] 2> 49 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 52 INFO (Thread-0) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 52 INFO (Thread-0) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 151 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer start zk server on port:36282
[junit4] 2> 184 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 225 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 237 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
[junit4] 2> 1416 INFO (zkCallback-1-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1414ef9 name:ZooKeeperConnection Watcher:127.0.0.1:36282 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1416 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1418 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1444 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 0x155bbc90cfb0000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
[junit4] 2> 1446 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1454 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1455 INFO (zkCallback-2-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9b00b0 name:ZooKeeperConnection Watcher:127.0.0.1:36282 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1456 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1456 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1457 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1468 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1468 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1470 INFO (zkCallback-3-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@187c49c name:ZooKeeperConnection Watcher:127.0.0.1:36282/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1470 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1470 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1490 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1496 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1498 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1503 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1507 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1519 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1525 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1529 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1543 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 1548 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1551 INFO (zkCallback-3-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1585 INFO (zkCallback-3-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> 1600 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1602 INFO (zkCallback-3-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> 1603 INFO (zkCallback-3-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> 1603 INFO (zkCallback-3-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1609 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36282 36282
[junit4] 2> 1669 INFO (Thread-0) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36282 36282
[junit4] 2> 1686 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
[junit4] 2> 1702 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
[junit4] 2> 1702 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1703 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1703 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1802 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer start zk server on port:33194
[junit4] 2> 1803 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1803 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1805 INFO (zkCallback-5-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1909c38 name:ZooKeeperConnection Watcher:127.0.0.1:33194 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1805 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1805 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1806 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1807 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1808 INFO (zkCallback-6-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ff71ec name:ZooKeeperConnection Watcher:127.0.0.1:33194 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1808 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1808 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1809 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1812 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1812 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1814 INFO (zkCallback-7-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4deb66 name:ZooKeeperConnection Watcher:127.0.0.1:33194/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1814 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1814 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1815 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1819 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1821 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1832 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1834 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1837 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1838 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 1839 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1841 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 1843 INFO (zkCallback-7-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1844 INFO (zkCallback-7-thread-1) [ ] o.a.s.c.c.ZkStateReader Adding lazy collectionRef for collection c1
[junit4] 2> 1844 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 1846 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Refreshing lazily-loaded state for collection c1
[junit4] 2> 1847 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 1851 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33194 33194
[junit4] 2> 1905 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33194 33194
[junit4] 2> 1906 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
[junit4] 2> 1911 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
[junit4] 2> 1912 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1934 INFO (Thread-2) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1935 INFO (Thread-2) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2034 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer start zk server on port:44001
[junit4] 2> 2035 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2036 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2037 INFO (zkCallback-9-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@cbe1ea name:ZooKeeperConnection Watcher:127.0.0.1:44001 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2037 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2038 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2039 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2048 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2049 INFO (zkCallback-10-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@70b912 name:ZooKeeperConnection Watcher:127.0.0.1:44001 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2049 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2049 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2049 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2052 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2053 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2054 INFO (zkCallback-11-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ff95ff name:ZooKeeperConnection Watcher:127.0.0.1:44001/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2054 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2054 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2055 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2059 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2061 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2064 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2066 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2067 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2068 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2068 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2070 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2070 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2072 INFO (zkCallback-11-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2072 INFO (zkCallback-11-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> 2073 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 2074 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2077 INFO (zkCallback-11-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> 2078 INFO (zkCallback-11-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> 2078 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 2079 INFO (zkCallback-11-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2083 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44001 44001
[junit4] 2> 2099 INFO (Thread-2) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44001 44001
[junit4] 2> 2100 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithExplicitRefresh -Dtests.seed=692FAEE60999ED4F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-NG -Dtests.timezone=Asia/Manila -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.20s J0 | ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
[junit4] > at __randomizedtesting.SeedInfo.seed([692FAEE60999ED4F:7695DF11D9F92B8A]: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> 2110 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 2112 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2112 INFO (Thread-3) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2112 INFO (Thread-3) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2212 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer start zk server on port:42727
[junit4] 2> 2213 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2213 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2220 INFO (zkCallback-13-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5517ff name:ZooKeeperConnection Watcher:127.0.0.1:42727 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2220 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2223 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2224 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 0x155bbc914bd0000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
[junit4] 2> 2224 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2229 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2230 INFO (zkCallback-14-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1b3e0ba name:ZooKeeperConnection Watcher:127.0.0.1:42727 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2230 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2232 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2232 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2238 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2238 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2240 INFO (zkCallback-15-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@15cafc7 name:ZooKeeperConnection Watcher:127.0.0.1:42727/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2240 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2240 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2241 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2243 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2245 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2248 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2253 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2256 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2259 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2260 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2261 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2263 INFO (zkCallback-15-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2266 INFO (zkCallback-15-thread-1) [ ] o.a.s.c.c.ZkStateReader Adding lazy collectionRef for collection c1
[junit4] 2> 2267 INFO (zkCallback-15-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> 2269 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2270 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2271 INFO (zkCallback-15-thread-2) [ ] o.a.s.c.c.ZkStateReader Uninteresting collection c1
[junit4] 2> 2274 INFO (zkCallback-15-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> 2275 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2279 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42727 42727
[junit4] 2> 2336 INFO (Thread-3) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42727 42727
[junit4] 2> 2336 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
[junit4] 2> 2341 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 2342 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2343 INFO (Thread-4) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2343 INFO (Thread-4) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2445 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer start zk server on port:41231
[junit4] 2> 2445 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2447 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2452 INFO (zkCallback-17-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f1e827 name:ZooKeeperConnection Watcher:127.0.0.1:41231 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2452 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2453 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2454 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2456 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2458 INFO (zkCallback-18-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1cf63c0 name:ZooKeeperConnection Watcher:127.0.0.1:41231 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2458 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2458 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2458 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2461 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2462 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2464 INFO (zkCallback-19-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@74c402 name:ZooKeeperConnection Watcher:127.0.0.1:41231/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2464 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2464 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2465 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2466 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2467 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2468 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2469 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2470 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2472 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2472 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2473 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2474 INFO (zkCallback-19-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2475 INFO (zkCallback-19-thread-1) [ ] o.a.s.c.c.ZkStateReader Adding lazy collectionRef for collection c1
[junit4] 2> 2475 INFO (zkCallback-19-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> 2476 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 2476 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2477 INFO (zkCallback-19-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> 2478 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Refreshing lazily-loaded state for collection c1
[junit4] 2> 2479 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 0x155bbc915a30002, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
[junit4] 2> 2480 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41231 41231
[junit4] 2> 2537 INFO (Thread-4) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41231 41231
[junit4] 2> 2538 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
[junit4] 2> 2541 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
[junit4] 2> 2541 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2541 INFO (Thread-5) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2541 INFO (Thread-5) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2641 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer start zk server on port:36618
[junit4] 2> 2642 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2643 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2645 INFO (zkCallback-21-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@306bf6 name:ZooKeeperConnection Watcher:127.0.0.1:36618 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2645 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2645 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2646 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2646 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2648 INFO (zkCallback-22-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1033402 name:ZooKeeperConnection Watcher:127.0.0.1:36618 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2648 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2648 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2648 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 2650 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 2650 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2652 INFO (zkCallback-23-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1887c20 name:ZooKeeperConnection Watcher:127.0.0.1:36618/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2652 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2652 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 2653 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 2654 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 2655 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 2656 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 2658 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 2658 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 2659 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
[junit4] 2> 2659 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 2661 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
[junit4] 2> 2661 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
[junit4] 2> 2662 INFO (zkCallback-23-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 2662 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.c.ZkStateReader Checking legacy cluster state for collection c1
[junit4] 2> 2664 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
[junit4] 2> 2665 INFO (zkCallback-23-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> 2665 INFO (zkCallback-23-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
[junit4] 2> 2668 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36618 36618
[junit4] 2> 2776 INFO (Thread-5) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36618 36618
[junit4] 2> 2777 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[692FAEE60999ED4F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
[junit4] 2> 2778 INFO (SUITE-ZkStateReaderTest-seed#[692FAEE60999ED4F]-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/J0/temp/solr.cloud.overseer.ZkStateReaderTest_692FAEE60999ED4F-001
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=15075, maxDocsPerChunk=3, blockSize=290), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=15075, blockSize=290)), sim=ClassicSimilarity, locale=en-NG, timezone=Asia/Manila
[junit4] 2> NOTE: Linux 4.4.0-28-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=48198408,total=83886080
[junit4] 2> NOTE: All tests run in this JVM: [ZkStateReaderTest]
[junit4] Completed [3/618 (1!)] on J0 in 3.88s, 6 tests, 1 error <<< FAILURES!
[...truncated 1982 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:1427: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:984: There were test failures: 618 suites (10 ignored), 2588 tests, 1 error, 117 ignored (70 assumptions) [seed: 692FAEE60999ED4F]
Total time: 58 minutes 29 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