Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/16914/ Java: 32bit/jdk-9-ea+120 -server -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay Error Message: Stack Trace: java.util.concurrent.TimeoutException at __randomizedtesting.SeedInfo.seed([F09C14703D3B3F12:8F02A3F554591298]:0) at org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1200) at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:125) at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:53) 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:531) 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 12105 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/J1/temp/solr.cloud.overseer.ZkStateReaderTest_F09C14703D3B3F12-001/init-core-data-001 [junit4] 2> 1696560 INFO (SUITE-ZkStateReaderTest-seed#[F09C14703D3B3F12]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 1696562 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation [junit4] 2> 1696562 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1696562 INFO (Thread-5457) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1696562 INFO (Thread-5457) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1696662 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer start zk server on port:33919 [junit4] 2> 1696662 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1696663 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1696664 INFO (zkCallback-2689-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a04979 name:ZooKeeperConnection Watcher:127.0.0.1:33919 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1696664 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1696664 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1696665 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1696665 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1696666 INFO (zkCallback-2690-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1abc118 name:ZooKeeperConnection Watcher:127.0.0.1:33919 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1696666 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1696667 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1696667 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1696669 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1696669 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1696670 INFO (zkCallback-2691-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a85335 name:ZooKeeperConnection Watcher:127.0.0.1:33919/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1696670 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1696670 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1696670 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1696671 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1696671 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1696672 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1696672 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1696673 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1696673 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1696673 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1696674 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 1696674 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1696674 INFO (zkCallback-2691-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1696675 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1696675 INFO (zkCallback-2691-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> 1696675 INFO (zkCallback-2691-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1696676 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33919 33919 [junit4] 2> 1696683 INFO (Thread-5457) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33919 33919 [junit4] 2> 1696683 INFO (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation [junit4] 2> 1696686 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy [junit4] 2> 1696686 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1696687 INFO (Thread-5458) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1696687 INFO (Thread-5458) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1696787 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer start zk server on port:43448 [junit4] 2> 1696787 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1696787 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1696789 INFO (zkCallback-2693-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f4d9dd name:ZooKeeperConnection Watcher:127.0.0.1:43448 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1696789 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1696789 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1696790 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1696790 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1696790 INFO (zkCallback-2694-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f6306c name:ZooKeeperConnection Watcher:127.0.0.1:43448 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1696790 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1696791 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1696791 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1696792 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1696792 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1696793 INFO (zkCallback-2695-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b62cb2 name:ZooKeeperConnection Watcher:127.0.0.1:43448/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1696793 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1696793 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1696793 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1696794 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1696794 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1696794 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1696795 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1696795 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1696795 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1696796 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1696796 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1696797 INFO (zkCallback-2695-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1696797 INFO (zkCallback-2695-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> 1696798 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 1696799 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1696799 INFO (zkCallback-2695-thread-1) [ ] o.a.s.c.c.ZkStateReader Uninteresting collection c1 [junit4] 2> 1696799 INFO (zkCallback-2695-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> 1696800 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1696801 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43448 43448 [junit4] 2> 1696823 INFO (Thread-5458) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43448 43448 [junit4] 2> 1696823 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy [junit4] 2> 1696827 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched [junit4] 2> 1696827 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1696827 INFO (Thread-5459) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1696827 INFO (Thread-5459) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1696927 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer start zk server on port:35229 [junit4] 2> 1696928 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1696928 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1696929 INFO (zkCallback-2697-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@17cd256 name:ZooKeeperConnection Watcher:127.0.0.1:35229 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1696930 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1696930 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1696931 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1696931 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1696931 INFO (zkCallback-2698-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1387eaa name:ZooKeeperConnection Watcher:127.0.0.1:35229 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1696931 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1696932 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1696932 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1696933 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1696934 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1696934 INFO (zkCallback-2699-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f9052d name:ZooKeeperConnection Watcher:127.0.0.1:35229/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1696934 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1696934 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1696934 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1696935 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1696935 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1696936 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1696936 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1696936 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1696937 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1696937 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1696937 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1696938 INFO (zkCallback-2699-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1696938 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1696938 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1696939 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35229 35229 [junit4] 2> 1697037 INFO (Thread-5459) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35229 35229 [junit4] 2> 1697037 INFO (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched [junit4] 2> 1697039 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh [junit4] 2> 1697039 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1697040 INFO (Thread-5460) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1697040 INFO (Thread-5460) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1697140 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer start zk server on port:35143 [junit4] 2> 1697140 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1697140 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1697141 INFO (zkCallback-2701-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7f496a name:ZooKeeperConnection Watcher:127.0.0.1:35143 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1697141 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1697141 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1697142 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1697142 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1697142 INFO (zkCallback-2702-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@188958f name:ZooKeeperConnection Watcher:127.0.0.1:35143 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1697143 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1697143 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1697143 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1697144 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1697144 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1697145 INFO (zkCallback-2703-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1767336 name:ZooKeeperConnection Watcher:127.0.0.1:35143/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1697145 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1697145 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1697145 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1697146 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1697146 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1697146 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1697147 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1697147 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1697147 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1697147 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1697149 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 1697149 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1697149 INFO (zkCallback-2703-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1697150 INFO (zkCallback-2703-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> 1697150 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1697150 INFO (zkCallback-2703-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> 1697150 INFO (zkCallback-2703-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1697150 INFO (zkCallback-2703-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> 1697151 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35143 35143 [junit4] 2> 1697153 INFO (Thread-5460) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35143 35143 [junit4] 2> 1697153 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh [junit4] 2> 1697155 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy [junit4] 2> 1697155 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1697155 INFO (Thread-5461) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1697155 INFO (Thread-5461) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1697255 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer start zk server on port:35649 [junit4] 2> 1697255 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1697255 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1697257 INFO (zkCallback-2705-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@145d9f9 name:ZooKeeperConnection Watcher:127.0.0.1:35649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1697257 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1697258 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1697258 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1697258 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1697259 INFO (zkCallback-2706-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1d71cce name:ZooKeeperConnection Watcher:127.0.0.1:35649 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1697259 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1697259 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1697259 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1697260 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1697261 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1697261 INFO (zkCallback-2707-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@308e9a name:ZooKeeperConnection Watcher:127.0.0.1:35649/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1697261 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1697261 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1697261 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1697262 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1697262 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1697263 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1697263 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1697264 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1697264 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1697264 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1697265 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1697265 INFO (zkCallback-2707-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1697266 INFO (zkCallback-2707-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> 1697266 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1697266 INFO (zkCallback-2707-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> 1697267 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35649 35649 [junit4] 2> 1697283 INFO (Thread-5461) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35649 35649 [junit4] 2> 1697283 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy [junit4] 2> 1697285 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay [junit4] 2> 1697286 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1697286 INFO (Thread-5462) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1697286 INFO (Thread-5462) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1697386 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer start zk server on port:34605 [junit4] 2> 1697386 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1697386 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1697387 INFO (zkCallback-2709-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7ab480 name:ZooKeeperConnection Watcher:127.0.0.1:34605 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1697387 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1697388 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1697388 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1697389 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1697389 INFO (zkCallback-2710-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@17139d9 name:ZooKeeperConnection Watcher:127.0.0.1:34605 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1697389 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1697389 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1697389 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1697390 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1697390 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1697391 INFO (zkCallback-2711-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@14c0533 name:ZooKeeperConnection Watcher:127.0.0.1:34605/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1697391 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1697391 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1697391 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1697392 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections [junit4] 2> 1697392 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1697392 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1697393 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1697393 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1697393 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1697393 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1697394 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.ZkStateReader Deleting data for [c1] [junit4] 2> 1697394 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/c1 [junit4] 2> 1697394 INFO (zkCallback-2711-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating... [junit4] 2> 1697395 INFO (zkCallback-2711-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> 1697395 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json [junit4] 2> 1697395 INFO (zkCallback-2711-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> 1697395 INFO (zkCallback-2711-thread-1) [ ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0] [junit4] 2> 1697395 INFO (zkCallback-2711-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> 1727397 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34605 34605 [junit4] 2> 1727503 INFO (Thread-5462) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34605 34605 [junit4] 2> 1727504 INFO (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F09C14703D3B3F12]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=F09C14703D3B3F12 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-GD -Dtests.timezone=America/Boise -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 30.2s J1 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<< [junit4] > Throwable #1: java.util.concurrent.TimeoutException [junit4] > at __randomizedtesting.SeedInfo.seed([F09C14703D3B3F12:8F02A3F554591298]:0) [junit4] > at org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1200) [junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:125) [junit4] > at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:53) [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> 1727506 INFO (SUITE-ZkStateReaderTest-seed#[F09C14703D3B3F12]-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/J1/temp/solr.cloud.overseer.ZkStateReaderTest_F09C14703D3B3F12-001 [junit4] 2> Jun 03, 2016 7:25:37 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=CheapBastard, sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=en-GD, timezone=America/Boise [junit4] 2> NOTE: Linux 4.4.0-21-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=189863744,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [UpdateRequestProcessorFactoryTest, SpatialFilterTest, TestQueryUtils, ExitableDirectoryReaderTest, AsyncMigrateRouteKeyTest, TestSurroundQueryParser, TimeZoneUtilsTest, ZkControllerTest, MultiTermTest, TestHdfsUpdateLog, SuggestComponentTest, CustomCollectionTest, OpenCloseCoreStressTest, PreAnalyzedUpdateProcessorTest, CoreAdminRequestStatusTest, SharedFSAutoReplicaFailoverUtilsTest, TestBulkSchemaConcurrent, TestHashQParserPlugin, TestMissingGroups, TestFieldTypeCollectionResource, TestDocumentBuilder, DistributedQueueTest, TestSolrDeletionPolicy2, ChaosMonkeyNothingIsSafeTest, TestInfoStreamLogging, TestTrie, SortByFunctionTest, TestExactSharedStatsCache, DOMUtilTest, TestReqParamsAPI, TestRTimerTree, TestUseDocValuesAsStored2, BasicFunctionalityTest, TestSortByMinMaxFunction, TestSolrConfigHandlerCloud, TestSubQueryTransformerDistrib, TermsComponentTest, TestQuerySenderNoQuery, TestGeoJSONResponseWriter, OpenExchangeRatesOrgProviderTest, SolrCmdDistributorTest, SoftAutoCommitTest, ExplicitHLLTest, RestartWhileUpdatingTest, TestSerializedLuceneMatchVersion, HdfsRecoveryZkTest, CollectionsAPIAsyncDistributedZkTest, TermVectorComponentTest, SaslZkACLProviderTest, FastVectorHighlighterTest, TestDocTermOrds, ResponseHeaderTest, TestNumericTerms32, SolrPluginUtilsTest, DirectSolrSpellCheckerTest, TestCopyFieldCollectionResource, TestFieldSortValues, LeaderInitiatedRecoveryOnShardRestartTest, TestBM25SimilarityFactory, TestEmbeddedSolrServerConstructors, TestSearcherReuse, DocValuesMissingTest, DataDrivenBlockJoinTest, CheckHdfsIndexTest, TestSystemIdResolver, DistribCursorPagingTest, SpellingQueryConverterTest, ZkCLITest, HdfsCollectionsAPIDistributedZkTest, UUIDUpdateProcessorFallbackTest, TestUniqueKeyFieldResource, SolrIndexSplitterTest, DistributedTermsComponentTest, SolrIndexConfigTest, TestReRankQParserPlugin, TestRestManager, MBeansHandlerTest, CSVRequestHandlerTest, BadCopyFieldTest, CursorMarkTest, TestFieldCacheVsDocValues, ShardRoutingCustomTest, HighlighterMaxOffsetTest, TestIntervalFaceting, ClusterStateUpdateTest, TestDynamicFieldResource, TestFastLRUCache, DocumentBuilderTest, WordBreakSolrSpellCheckerTest, HLLUtilTest, TestOnReconnectListenerSupport, DistributedExpandComponentTest, TestDistributedGrouping, FacetPivotSmallTest, DeleteReplicaTest, TestBlendedInfixSuggestions, TestManagedSchemaAPI, AliasIntegrationTest, TestRawTransformer, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, ShardRoutingTest, TestReplicationHandler, TestRandomDVFaceting, TestStressVersions, TestCoreContainer, SimpleFacetsTest, SolrCoreTest, SpellCheckComponentTest, TestFunctionQuery, TestBadConfig, SimplePostToolTest, TestCoreDiscovery, SpellCheckCollatorTest, TestFoldingMultitermQuery, DocValuesTest, PathHierarchyTokenizerFactoryTest, TestArbitraryIndexDir, IndexSchemaTest, ReturnFieldsTest, QueryParsingTest, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, PingRequestHandlerTest, SpellPossibilityIteratorTest, TestCharFilters, EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestJmxMonitoredMap, PluginInfoTest, PreAnalyzedFieldTest, TestSolrJ, ZkNodePropsTest, FileUtilsTest, CursorPagingTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestSimpleTrackingShardHandler, AsyncCallRequestStatusResponseTest, CollectionsAPISolrJTest, TestConfigSetsAPI, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestLockTree, TestMiniSolrCloudCluster, TestRequestForwarding, TestSSLRandomization, TestShortCircuitedRequests, TestSolrCloudWithKerberosAlt, TestStressCloudBlindAtomicUpdates, TestTolerantUpdateProcessorCloud, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, VMParamsZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest, HdfsChaosMonkeyNothingIsSafeTest, HdfsNNFailoverTest, HdfsRestartWhileUpdatingTest, HdfsSyncSliceTest, HdfsThreadLeakTest, HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, ZkStateReaderTest] [junit4] Completed [505/612 (1!)] on J1 in 31.71s, 6 tests, 1 error <<< FAILURES! [...truncated 363 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:531: 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: 612 suites (10 ignored), 2560 tests, 1 error, 118 ignored (72 assumptions) [seed: F09C14703D3B3F12] Total time: 58 minutes 5 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
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org