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 16:29:37 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (32bit/jdk-9-ea+125) - Build
# 17158 - Still Failing!
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