lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-6.x-Solaris (64bit/jdk1.8.0) - Build # 132 - Still Failing!
Date Sat, 14 May 2016 19:15:21 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Solaris/132/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

2 tests failed.
FAILED:  org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay

Error Message:
expected:<2> but was:<1>

Stack Trace:
java.lang.AssertionError: expected:<2> but was:<1>
	at __randomizedtesting.SeedInfo.seed([3E2C1BBBC480F7D1:41B2AC3EADE2DA5B]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:138)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:52)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh

Error Message:


Stack Trace:
java.lang.NullPointerException
	at __randomizedtesting.SeedInfo.seed([3E2C1BBBC480F7D1:21966A4C14E03114]:0)
	at org.apache.solr.common.cloud.ZkStateReader.refreshLegacyClusterState(ZkStateReader.java:522)
	at org.apache.solr.common.cloud.ZkStateReader.forceUpdateCollection(ZkStateReader.java:296)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:127)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:42)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 10584 lines...]
   [junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
   [junit4]   2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_3E2C1BBBC480F7D1-001/init-core-data-001
   [junit4]   2> 32644 INFO  (SUITE-ZkStateReaderTest-seed#[3E2C1BBBC480F7D1]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true)
   [junit4]   2> 32679 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 32688 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 32708 INFO  (Thread-75) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 32709 INFO  (Thread-75) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 32808 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer start zk server on port:35682
   [junit4]   2> 32837 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 32898 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 32922 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> 34468 INFO  (zkCallback-26-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@77070367 name:ZooKeeperConnection Watcher:127.0.0.1:35682 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 34468 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 34468 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 34486 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 34487 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 34490 INFO  (zkCallback-27-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a161d3c name:ZooKeeperConnection Watcher:127.0.0.1:35682 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 34490 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 34490 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 34492 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 34509 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 0x154b07c3d840001, likely client has closed socket
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 34513 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 34513 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 34516 INFO  (zkCallback-28-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5bebce02 name:ZooKeeperConnection Watcher:127.0.0.1:35682/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 34516 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 34516 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 34536 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 34541 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 34543 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 34545 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 34548 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 34558 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 34564 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 34570 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 34583 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 34590 INFO  (zkCallback-28-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 34615 INFO  (zkCallback-28-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> 34625 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 34631 INFO  (zkCallback-28-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> 34654 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35682 35682
   [junit4]   2> 34669 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxnFactory Ignoring unexpected runtime exception
   [junit4]   2> java.nio.channels.CancelledKeyException
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:187)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 34669 INFO  (Thread-75) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35682 35682
   [junit4]   2> 34687 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 34693 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 34693 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 34694 INFO  (Thread-76) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 34694 INFO  (Thread-76) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 34794 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer start zk server on port:41331
   [junit4]   2> 34794 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 34794 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 34799 INFO  (zkCallback-30-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@204a382b name:ZooKeeperConnection Watcher:127.0.0.1:41331 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 34799 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 34800 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 34804 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 34804 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 34807 INFO  (zkCallback-31-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@34e60233 name:ZooKeeperConnection Watcher:127.0.0.1:41331 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 34807 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 34807 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 34807 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 34816 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 34816 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 34819 INFO  (zkCallback-32-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e616831 name:ZooKeeperConnection Watcher:127.0.0.1:41331/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 34819 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 34819 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 34821 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 34823 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 34826 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 34829 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 34831 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 34833 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 34833 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 34834 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 34841 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 34842 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 34849 INFO  (zkCallback-32-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 34851 INFO  (zkCallback-32-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> 34901 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 34904 INFO  (zkCallback-32-thread-2) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [0])
   [junit4]   2> 34904 INFO  (zkCallback-32-thread-2) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 34905 INFO  (zkCallback-32-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> 85329 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41331 41331
   [junit4]   2> 95499 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxnFactory Ignoring unexpected runtime exception
   [junit4]   2> java.nio.channels.CancelledKeyException
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:187)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 95500 INFO  (Thread-76) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:41331 41331
   [junit4]   2> 95503 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=3E2C1BBBC480F7D1 -Dtests.slow=true -Dtests.locale=ar-IQ -Dtests.timezone=Pacific/Tongatapu -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 60.8s J0 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<2> but was:<1>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([3E2C1BBBC480F7D1:41B2AC3EADE2DA5B]:0)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:138)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:52)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 95506 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 95507 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 95507 INFO  (Thread-77) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 95507 INFO  (Thread-77) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 95607 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer start zk server on port:51378
   [junit4]   2> 95607 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 95608 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 95611 INFO  (zkCallback-34-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@8036e57 name:ZooKeeperConnection Watcher:127.0.0.1:51378 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 95612 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 95612 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 95615 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 95616 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 95619 INFO  (zkCallback-35-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@39f51417 name:ZooKeeperConnection Watcher:127.0.0.1:51378 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 95619 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 95619 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 95619 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 95625 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 95625 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 95627 INFO  (zkCallback-36-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6767f9c name:ZooKeeperConnection Watcher:127.0.0.1:51378/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 95628 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 95628 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 95630 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 95633 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 95636 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 95639 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 95643 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 95645 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 95647 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 95648 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 95652 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 95652 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 95656 INFO  (zkCallback-36-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 95659 INFO  (zkCallback-36-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> 95662 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 95664 INFO  (zkCallback-36-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> 95666 INFO  (zkCallback-36-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 95666 INFO  (zkCallback-36-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> 95671 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51378 51378
   [junit4]   2> 100740 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxnFactory Ignoring unexpected runtime exception
   [junit4]   2> java.nio.channels.CancelledKeyException
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:187)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 100741 INFO  (Thread-77) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:51378 51378
   [junit4]   2> 100744 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithExplicitRefresh -Dtests.seed=3E2C1BBBC480F7D1 -Dtests.slow=true -Dtests.locale=ar-IQ -Dtests.timezone=Pacific/Tongatapu -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   5.24s J0 | ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh <<<
   [junit4]    > Throwable #1: java.lang.NullPointerException
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([3E2C1BBBC480F7D1:21966A4C14E03114]:0)
   [junit4]    > 	at org.apache.solr.common.cloud.ZkStateReader.refreshLegacyClusterState(ZkStateReader.java:522)
   [junit4]    > 	at org.apache.solr.common.cloud.ZkStateReader.forceUpdateCollection(ZkStateReader.java:296)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:127)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh(ZkStateReaderTest.java:42)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 100752 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 100752 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 100753 INFO  (Thread-78) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 100753 INFO  (Thread-78) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 100853 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer start zk server on port:40188
   [junit4]   2> 100853 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 100853 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 100857 INFO  (zkCallback-38-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b134738 name:ZooKeeperConnection Watcher:127.0.0.1:40188 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 100857 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 100857 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 100860 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 100861 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 100863 INFO  (zkCallback-39-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@69bad886 name:ZooKeeperConnection Watcher:127.0.0.1:40188 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 100864 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 100864 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 100864 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 100870 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 100870 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 100874 INFO  (zkCallback-40-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@61ed5344 name:ZooKeeperConnection Watcher:127.0.0.1:40188/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 100874 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 100874 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 100876 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 100878 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 100881 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 100883 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 100886 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 100887 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 100889 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 100890 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 100892 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 100896 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 100899 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 100902 ERROR (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]-EventThread) [    ] o.a.z.ClientCnxn Error while calling watcher 
   [junit4]   2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$8/728385686@3e19f1a0 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@3a2662bb[Shutting down, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 1]
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214)
   [junit4]   2> 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
   [junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
   [junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
   [junit4]   2> 100905 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40188 40188
   [junit4]   2> 101007 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxnFactory Ignoring unexpected runtime exception
   [junit4]   2> java.nio.channels.CancelledKeyException
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:187)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 101009 INFO  (Thread-78) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40188 40188
   [junit4]   2> 101010 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> 101013 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 101014 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 101014 INFO  (Thread-79) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 101014 INFO  (Thread-79) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 101114 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer start zk server on port:58034
   [junit4]   2> 101114 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 101114 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 101118 INFO  (zkCallback-42-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@103eddb3 name:ZooKeeperConnection Watcher:127.0.0.1:58034 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 101118 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 101118 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 101121 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 101122 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 101124 INFO  (zkCallback-43-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e942b8f name:ZooKeeperConnection Watcher:127.0.0.1:58034 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 101124 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 101124 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 101124 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 101130 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 101131 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 101133 INFO  (zkCallback-44-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@23abcb6 name:ZooKeeperConnection Watcher:127.0.0.1:58034/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 101133 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 101134 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 101136 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 101140 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 101143 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 101146 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 101148 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 101151 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 101153 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 101154 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 101157 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 101159 INFO  (zkCallback-44-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 101162 INFO  (zkCallback-44-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> 101164 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 101166 INFO  (zkCallback-44-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> 101171 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58034 58034
   [junit4]   2> 111195 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxnFactory Ignoring unexpected runtime exception
   [junit4]   2> java.nio.channels.CancelledKeyException
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:187)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 111196 INFO  (Thread-79) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58034 58034
   [junit4]   2> 111198 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 111200 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
   [junit4]   2> 111201 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 111201 INFO  (Thread-80) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 111201 INFO  (Thread-80) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 111306 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer start zk server on port:59538
   [junit4]   2> 111306 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 111307 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 111317 INFO  (zkCallback-46-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@453eaefb name:ZooKeeperConnection Watcher:127.0.0.1:59538 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 111317 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 111317 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 111320 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 111321 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 111323 INFO  (zkCallback-47-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@488a567e name:ZooKeeperConnection Watcher:127.0.0.1:59538 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 111323 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 111323 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 111323 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 111329 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 111330 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 111331 INFO  (zkCallback-48-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3b9ffc7d name:ZooKeeperConnection Watcher:127.0.0.1:59538/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 111332 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 111332 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 111333 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 111336 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 111339 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 111341 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 111344 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 111346 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 111347 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties
   [junit4]   2> 111349 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
   [junit4]   2> 111353 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 111353 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 111356 INFO  (zkCallback-48-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 111359 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 111360 INFO  (zkCallback-48-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> 111361 INFO  (zkCallback-48-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 111414 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59538 59538
   [junit4]   2> 116520 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxnFactory Ignoring unexpected runtime exception
   [junit4]   2> java.nio.channels.CancelledKeyException
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
   [junit4]   2> 	at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
   [junit4]   2> 	at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:187)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 116521 INFO  (Thread-80) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59538 59538
   [junit4]   2> 116522 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[3E2C1BBBC480F7D1]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
   [junit4]   2> 116523 INFO  (SUITE-ZkStateReaderTest-seed#[3E2C1BBBC480F7D1]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_3E2C1BBBC480F7D1-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=601, maxMBSortInHeap=5.364084293221849, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=ar-IQ, timezone=Pacific/Tongatapu
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_92 (64-bit)/cpus=3,threads=1,free=25420376,total=64880640
   [junit4]   2> NOTE: All tests run in this JVM: [TestSort, DistributedQueryElevationComponentTest, CdcrRequestHandlerTest, TestSweetSpotSimilarityFactory, CurrencyFieldXmlFileTest, SortSpecParsingTest, DirectUpdateHandlerTest, ZkStateReaderTest]
   [junit4] Completed [34/598 (1!)] on J0 in 83.90s, 6 tests, 1 failure, 1 error <<< FAILURES!

[...truncated 1802 lines...]
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/build.xml:740: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/build.xml:684: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/build.xml:59: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build.xml:233: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/common-build.xml:529: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/lucene/common-build.xml:1457: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/lucene/common-build.xml:1014: There were test failures: 598 suites (10 ignored), 2420 tests, 1 error, 1 failure, 87 ignored (72 assumptions) [seed: 3E2C1BBBC480F7D1]

Total time: 78 minutes 57 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



Mime
View raw message