You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/02/13 19:48:55 UTC

[JENKINS] Lucene-Solr-Tests-5.5 - Build # 25 - Failure

Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.5/25/

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

Error Message:
Could not find collection : c1

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : c1
	at __randomizedtesting.SeedInfo.seed([F3AE569F025DE351:8C30E11A6B3FCEDB]:0)
	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
	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:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	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 11096 lines...]
   [junit4] Suite: org.apache.solr.cloud.overseer.ZkStateReaderTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_F3AE569F025DE351-001/init-core-data-001
   [junit4]   2> 808131 INFO  (SUITE-ZkStateReaderTest-seed#[F3AE569F025DE351]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 808133 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 808133 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 808137 INFO  (Thread-2604) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 808137 INFO  (Thread-2604) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 808237 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer start zk server on port:58555
   [junit4]   2> 808237 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 808237 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 808239 INFO  (zkCallback-1551-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ed5dad3 name:ZooKeeperConnection Watcher:127.0.0.1:58555 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 808240 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 808240 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 808241 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 808242 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 808242 INFO  (zkCallback-1552-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f516422 name:ZooKeeperConnection Watcher:127.0.0.1:58555 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 808242 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 808242 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 808242 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 808246 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 808247 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 808248 INFO  (zkCallback-1553-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@28cb4f6e name:ZooKeeperConnection Watcher:127.0.0.1:58555/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 808248 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 808248 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 808248 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 808249 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 808250 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 808251 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 808251 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 808252 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 808253 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 808254 INFO  (zkCallback-1553-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 808255 INFO  (zkCallback-1553-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> 808256 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 808256 INFO  (zkCallback-1553-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> 808258 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58555 58555
   [junit4]   2> 808398 INFO  (Thread-2604) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58555 58555
   [junit4]   2> 808399 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelayLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelayLazy
   [junit4]   2> 808401 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 808402 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 808402 INFO  (Thread-2605) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 808402 INFO  (Thread-2605) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 808502 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer start zk server on port:37565
   [junit4]   2> 808502 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 808503 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 808504 INFO  (zkCallback-1554-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6dbb494a name:ZooKeeperConnection Watcher:127.0.0.1:37565 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 808504 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 808504 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 808505 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 808506 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 808507 INFO  (zkCallback-1555-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4ca42b23 name:ZooKeeperConnection Watcher:127.0.0.1:37565 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 808507 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 808507 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 808507 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 808509 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 808509 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 808510 INFO  (zkCallback-1556-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@636c4a4f name:ZooKeeperConnection Watcher:127.0.0.1:37565/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 808510 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 808510 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 808510 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 808511 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 808512 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 808513 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 808514 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 808515 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 808516 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 808517 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 808517 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 808517 INFO  (zkCallback-1556-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 808519 INFO  (zkCallback-1556-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> 808520 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 808521 INFO  (zkCallback-1556-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> 808521 INFO  (zkCallback-1556-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 808521 INFO  (zkCallback-1556-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> 808523 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37565 37565
   [junit4]   2> 808642 INFO  (Thread-2605) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37565 37565
   [junit4]   2> 808644 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefresh-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefresh
   [junit4]   2> 808646 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 808646 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 808646 INFO  (Thread-2606) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 808647 INFO  (Thread-2606) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 808747 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer start zk server on port:42148
   [junit4]   2> 808747 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 808747 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 808749 INFO  (zkCallback-1557-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7b037fe6 name:ZooKeeperConnection Watcher:127.0.0.1:42148 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 808749 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 808749 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 808750 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 808751 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 808753 INFO  (zkCallback-1558-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1dae7611 name:ZooKeeperConnection Watcher:127.0.0.1:42148 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 808753 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 808753 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 808753 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 808755 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 808756 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 808758 INFO  (zkCallback-1559-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3b4a1861 name:ZooKeeperConnection Watcher:127.0.0.1:42148/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 808758 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 808758 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 808758 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 808759 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 808760 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 808760 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 808761 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 808762 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 808763 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 808764 INFO  (zkCallback-1559-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 808765 INFO  (zkCallback-1559-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> 808766 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 808766 INFO  (zkCallback-1559-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> 808768 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42148 42148
   [junit4]   2> 808901 INFO  (Thread-2606) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42148 42148
   [junit4]   2> 808902 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithExplicitRefreshLazy-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithExplicitRefreshLazy
   [junit4]   2> 808904 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testWatchedCollectionCreation
   [junit4]   2> 808905 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 808905 INFO  (Thread-2607) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 808905 INFO  (Thread-2607) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 809005 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer start zk server on port:57935
   [junit4]   2> 809005 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 809005 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 809007 INFO  (zkCallback-1560-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7347c378 name:ZooKeeperConnection Watcher:127.0.0.1:57935 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 809007 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 809008 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 809009 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 809009 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 809010 INFO  (zkCallback-1561-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@29609658 name:ZooKeeperConnection Watcher:127.0.0.1:57935 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 809011 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 809011 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 809011 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 809012 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 809013 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 809013 INFO  (zkCallback-1562-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3c797fa9 name:ZooKeeperConnection Watcher:127.0.0.1:57935/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 809013 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 809014 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 809014 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 809015 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 809015 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 809016 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 809017 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 809017 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 809018 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 809019 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 809019 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 809020 INFO  (zkCallback-1562-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 809021 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 809021 INFO  (zkCallback-1562-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> 809021 INFO  (zkCallback-1562-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 809073 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57935 57935
   [junit4]   2> 809283 INFO  (Thread-2607) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:57935 57935
   [junit4]   2> 809284 INFO  (TEST-ZkStateReaderTest.testWatchedCollectionCreation-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testWatchedCollectionCreation
   [junit4]   2> 809286 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExternalCollectionWatchedNotWatched
   [junit4]   2> 809286 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 809287 INFO  (Thread-2608) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 809287 INFO  (Thread-2608) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 809387 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer start zk server on port:44547
   [junit4]   2> 809387 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 809390 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 809391 INFO  (zkCallback-1563-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4480b55a name:ZooKeeperConnection Watcher:127.0.0.1:44547 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 809391 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 809392 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 809393 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 809393 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 809395 INFO  (zkCallback-1564-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@11a5f65 name:ZooKeeperConnection Watcher:127.0.0.1:44547 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 809395 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 809395 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 809395 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 809398 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 809398 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 809400 INFO  (zkCallback-1565-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@75e192b6 name:ZooKeeperConnection Watcher:127.0.0.1:44547/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 809400 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 809400 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 809400 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 809401 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 809402 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 809403 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 809404 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 809405 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 809406 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 809407 INFO  (zkCallback-1565-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 809408 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 809409 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 809409 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 809409 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader Removing watch for uninteresting collection [c1]
   [junit4]   2> 809411 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44547 44547
   [junit4]   2> 809583 INFO  (Thread-2608) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44547 44547
   [junit4]   2> 809584 INFO  (TEST-ZkStateReaderTest.testExternalCollectionWatchedNotWatched-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExternalCollectionWatchedNotWatched
   [junit4]   2> 809586 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStateFormatUpdateWithTimeDelay
   [junit4]   2> 809587 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 809587 INFO  (Thread-2609) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 809587 INFO  (Thread-2609) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 809687 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer start zk server on port:49891
   [junit4]   2> 809687 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 809688 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 809693 INFO  (zkCallback-1566-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6b81f595 name:ZooKeeperConnection Watcher:127.0.0.1:49891 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 809693 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 809693 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 809694 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 809695 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 809695 INFO  (zkCallback-1567-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@30cd92dc name:ZooKeeperConnection Watcher:127.0.0.1:49891 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 809695 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 809695 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 809695 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 809697 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 809697 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 809698 INFO  (zkCallback-1568-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6c4c3ba8 name:ZooKeeperConnection Watcher:127.0.0.1:49891/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 809698 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 809698 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 809699 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 809699 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections
   [junit4]   2> 809700 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 809701 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 809702 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 809702 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 809704 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader addZkWatch [c1]
   [junit4]   2> 809704 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.ZkStateReader Deleting data for [c1]
   [junit4]   2> 809704 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/c1
   [junit4]   2> 809705 INFO  (zkCallback-1568-thread-1) [    ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
   [junit4]   2> 809706 INFO  (zkCallback-1568-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> 809757 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/c1/state.json
   [junit4]   2> 809758 INFO  (zkCallback-1568-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> 809758 INFO  (zkCallback-1568-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> 809759 INFO  (zkCallback-1568-thread-1) [    ] o.a.s.c.c.ZkStateReader Add data for [c1] ver [0]
   [junit4]   2> 809760 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:49891 49891
   [junit4]   2> 809915 INFO  (Thread-2609) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:49891 49891
   [junit4]   2> 809916 INFO  (TEST-ZkStateReaderTest.testStateFormatUpdateWithTimeDelay-seed#[F3AE569F025DE351]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStateFormatUpdateWithTimeDelay
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ZkStateReaderTest -Dtests.method=testStateFormatUpdateWithTimeDelay -Dtests.seed=F3AE569F025DE351 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ar-LY -Dtests.timezone=Asia/Urumqi -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.33s J0 | ZkStateReaderTest.testStateFormatUpdateWithTimeDelay <<<
   [junit4]    > Throwable #1: org.apache.solr.common.SolrException: Could not find collection : c1
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([F3AE569F025DE351:8C30E11A6B3FCEDB]:0)
   [junit4]    > 	at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:170)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdate(ZkStateReaderTest.java:129)
   [junit4]    > 	at org.apache.solr.cloud.overseer.ZkStateReaderTest.testStateFormatUpdateWithTimeDelay(ZkStateReaderTest.java:52)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 809918 INFO  (SUITE-ZkStateReaderTest-seed#[F3AE569F025DE351]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.overseer.ZkStateReaderTest_F3AE569F025DE351-001
   [junit4]   2> Feb 13, 2017 7:20:12 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54): {}, docValues:{}, sim=DefaultSimilarity, locale=ar-LY, timezone=Asia/Urumqi
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=230090496,total=430964736
   [junit4]   2> NOTE: All tests run in this JVM: [ActionThrottleTest, TestCollationFieldDocValues, BlockJoinFacetDistribTest, IndexSchemaRuntimeFieldTest, TestPhraseSuggestions, TestScoreJoinQPNoScore, TestComplexPhraseQParserPlugin, TestRandomCollapseQParserPlugin, TestLazyCores, AnalysisAfterCoreReloadTest, TestRandomDVFaceting, TestMissingGroups, JsonLoaderTest, BasicDistributedZkTest, ZkNodePropsTest, TestRTimerTree, DeleteInactiveReplicaTest, SignatureUpdateProcessorFactoryTest, TestConfigSetProperties, SuggestComponentTest, DistributedExpandComponentTest, EnumFieldTest, TestSolrConfigHandler, MigrateRouteKeyTest, TestRealTimeGet, CollectionStateFormat2Test, TestMergePolicyConfig, HdfsRecoverLeaseTest, TestXmlQParser, TestSimpleQParserPlugin, TestReplicationHandlerBackup, TestRangeQuery, PathHierarchyTokenizerFactoryTest, TestCSVLoader, ExternalFileFieldSortTest, TestValueSourceCache, TestOmitPositions, TestSolrQueryResponse, ResponseLogComponentTest, UUIDUpdateProcessorFallbackTest, PreAnalyzedFieldTest, TestDocBasedVersionConstraints, SolrXmlInZkTest, FileBasedSpellCheckerTest, TestIBSimilarityFactory, TestNRTOpen, QueryEqualityTest, AlternateDirectoryTest, TestAnalyzedSuggestions, TestExactSharedStatsCache, RecoveryAfterSoftCommitTest, TestMiniSolrCloudCluster, AnalyticsMergeStrategyTest, RestartWhileUpdatingTest, TestSchemaSimilarityResource, TestStandardQParsers, CleanupOldIndexTest, HdfsChaosMonkeySafeLeaderTest, TestHashQParserPlugin, RequiredFieldsTest, TestIntervalFaceting, LeaderElectionIntegrationTest, EchoParamsTest, XsltUpdateRequestHandlerTest, TestSolrConfigHandlerCloud, UUIDFieldTest, TestRemoteStreaming, QueryElevationComponentTest, AddBlockUpdateTest, BasicZkTest, TestSolrQueryParserDefaultOperatorResource, PrimUtilsTest, TestFunctionQuery, TestRTGBase, TestRemoveLastDynamicCopyField, ExplicitHLLTest, SolrCoreTest, SOLR749Test, TestFoldingMultitermQuery, ConnectionManagerTest, CollectionsAPISolrJTest, TestManagedStopFilterFactory, TestClassNameShortening, ZkStateReaderTest]
   [junit4] Completed [177/575 (1!)] on J0 in 2.30s, 6 tests, 1 error <<< FAILURES!

[...truncated 1281 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:750: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:694: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/solr/common-build.xml:534: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/common-build.xml:1477: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/common-build.xml:1033: There were test failures: 575 suites (7 ignored), 2323 tests, 1 error, 55 ignored (39 assumptions) [seed: F3AE569F025DE351]

Total time: 66 minutes 52 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any




Re: [JENKINS] Lucene-Solr-Tests-5.5 - Build # 26 - Still Failing

Posted by Michael McCandless <lu...@mikemccandless.com>.
Thanks Adrien!

Mike McCandless

http://blog.mikemccandless.com


On Tue, Feb 14, 2017 at 3:26 AM, Adrien Grand <jp...@gmail.com> wrote:
> This is a test bug that can only happen on branch_5x. The test tries to
> change the first X bytes of the file, but when the first X bytes happen to
> be the same as the original bytes, it retries, which causes
> MockDirectoryWrapper to complain that the file has already been written to.
> I fixed the test to just generate random bytes until they are different from
> the first bytes of the file, without writing multiple times.
>
> Le mar. 14 févr. 2017 à 03:10, Steve Rowe <sa...@gmail.com> a écrit :
>>
>> This failure reproduces for me on branch_5_5, but not on master or
>> branch_6x.
>>
>> --
>> Steve
>> www.lucidworks.com
>>
>> > On Feb 13, 2017, at 9:03 PM, Apache Jenkins Server
>> > <je...@builds.apache.org> wrote:
>> >
>> > Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.5/26/
>> >
>> > 1 tests failed.
>> > FAILED:  org.apache.lucene.index.TestAllFilesCheckIndexHeader.test
>> >
>> > Error Message:
>> > file "_h_Lucene50_0.doc" was already written to
>> >
>> > Stack Trace:
>> > java.io.IOException: file "_h_Lucene50_0.doc" was already written to
>> >       at
>> > __randomizedtesting.SeedInfo.seed([4BC507D87C083D63:C3913802D2F4509B]:0)
>> >       at
>> > org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:558)
>> >       at
>> > org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkOneFile(TestAllFilesCheckIndexHeader.java:111)
>> >       at
>> > org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkIndexHeader(TestAllFilesCheckIndexHeader.java:87)
>> >       at
>> > org.apache.lucene.index.TestAllFilesCheckIndexHeader.test(TestAllFilesCheckIndexHeader.java:80)
>> >       at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>> >       at
>> > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
>> >       at
>> > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>> >       at java.lang.reflect.Method.invoke(Method.java:606)
>> >       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
>> > 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
>> > 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 723 lines...]
>> >   [junit4] Suite: org.apache.lucene.index.TestAllFilesCheckIndexHeader
>> >   [junit4]   2> NOTE: reproduce with: ant test
>> > -Dtestcase=TestAllFilesCheckIndexHeader -Dtests.method=test
>> > -Dtests.seed=4BC507D87C083D63 -Dtests.multiplier=2 -Dtests.slow=true
>> > -Dtests.locale=fi -Dtests.timezone=Etc/GMT+4 -Dtests.asserts=true
>> > -Dtests.file.encoding=UTF-8
>> >   [junit4] ERROR   0.82s J2 | TestAllFilesCheckIndexHeader.test <<<
>> >   [junit4]    > Throwable #1: java.io.IOException: file
>> > "_h_Lucene50_0.doc" was already written to
>> >   [junit4]    >       at
>> > __randomizedtesting.SeedInfo.seed([4BC507D87C083D63:C3913802D2F4509B]:0)
>> >   [junit4]    >       at
>> > org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:558)
>> >   [junit4]    >       at
>> > org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkOneFile(TestAllFilesCheckIndexHeader.java:111)
>> >   [junit4]    >       at
>> > org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkIndexHeader(TestAllFilesCheckIndexHeader.java:87)
>> >   [junit4]    >       at
>> > org.apache.lucene.index.TestAllFilesCheckIndexHeader.test(TestAllFilesCheckIndexHeader.java:80)
>> >   [junit4]    >       at java.lang.Thread.run(Thread.java:745)
>> >   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54): {},
>> > docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=yes): {body=IB
>> > LL-DZ(0.3), titleTokenized=DFR I(n)B3(800.0)}, locale=fi, timezone=Etc/GMT+4
>> >   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
>> > 1.7.0_80 (64-bit)/cpus=4,threads=1,free=131735736,total=350224384
>> >   [junit4]   2> NOTE: All tests run in this JVM:
>> > [TestCachingTokenFilter, TestSpanNearQuery, TestNoDeletionPolicy,
>> > TestNumericRangeQuery32, TestReaderWrapperDVTypeCheck,
>> > TestTimSorterWorstCase, TestAutomatonQuery, TestDocValuesRewriteMethod,
>> > TestMathUtil, TestLazyProxSkipping, TestTransactions,
>> > FuzzyTermOnShortTermsTest, TestBlockPostingsFormat, TestSumDocFreq,
>> > TestUnicodeUtil, TestNumericTokenStream, TestTopDocsMerge, TestRegexpRandom,
>> > TestCharFilter, TestFilterDirectoryReader, TestStressNRT, TestOmitTf,
>> > TestLucene50TermVectorsFormat, TestSpanTermQuery, TestPrefixQuery,
>> > TestBooleanCoord, TestPrefixInBooleanQuery, TestCompiledAutomaton,
>> > Test2BNumericDocValues, TestIndexWriterUnicode, TestForUtil,
>> > TestConcurrentMergeScheduler, TestMultiTermQueryRewrites,
>> > TestSloppyPhraseQuery2, TestFilteredSearch, TestSearchWithThreads,
>> > TestPerFieldPostingsFormat2, TestIndexWriterReader,
>> > TestBooleanQueryVisitSubscorers, TestTermVectorsWriter, TestMaxPosition,
>> > TestAllFilesCheckIndexHeader]
>> >   [junit4] Completed [160/423 (1!)] on J2 in 0.84s, 1 test, 1 error <<<
>> > FAILURES!
>> >
>> > [...truncated 848 lines...]
>> > BUILD FAILED
>> > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:750:
>> > The following error occurred while executing this line:
>> > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:694:
>> > The following error occurred while executing this line:
>> > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:59:
>> > The following error occurred while executing this line:
>> >
>> > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/build.xml:50:
>> > The following error occurred while executing this line:
>> >
>> > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/common-build.xml:1477:
>> > The following error occurred while executing this line:
>> >
>> > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/common-build.xml:1033:
>> > There were test failures: 423 suites (1 ignored), 3466 tests, 1 error, 49
>> > ignored (45 assumptions) [seed: 4BC507D87C083D63]
>> >
>> > Total time: 7 minutes 28 seconds
>> > Build step 'Invoke Ant' marked build as failure
>> > Archiving artifacts
>> > Recording test results
>> > Email was triggered for: Failure - Any
>> > Sending email for trigger: Failure - Any
>> >
>> >
>> >
>> >
>> > ---------------------------------------------------------------------
>> > To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
>> > For additional commands, e-mail: dev-help@lucene.apache.org
>>
>

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org


Re: [JENKINS] Lucene-Solr-Tests-5.5 - Build # 26 - Still Failing

Posted by Adrien Grand <jp...@gmail.com>.
This is a test bug that can only happen on branch_5x. The test tries to
change the first X bytes of the file, but when the first X bytes happen to
be the same as the original bytes, it retries, which causes
MockDirectoryWrapper to complain that the file has already been written to.
I fixed the test to just generate random bytes until they are different
from the first bytes of the file, without writing multiple times.

Le mar. 14 févr. 2017 à 03:10, Steve Rowe <sa...@gmail.com> a écrit :

> This failure reproduces for me on branch_5_5, but not on master or
> branch_6x.
>
> --
> Steve
> www.lucidworks.com
>
> > On Feb 13, 2017, at 9:03 PM, Apache Jenkins Server <
> jenkins@builds.apache.org> wrote:
> >
> > Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.5/26/
> >
> > 1 tests failed.
> > FAILED:  org.apache.lucene.index.TestAllFilesCheckIndexHeader.test
> >
> > Error Message:
> > file "_h_Lucene50_0.doc" was already written to
> >
> > Stack Trace:
> > java.io.IOException: file "_h_Lucene50_0.doc" was already written to
> >       at
> __randomizedtesting.SeedInfo.seed([4BC507D87C083D63:C3913802D2F4509B]:0)
> >       at
> org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:558)
> >       at
> org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkOneFile(TestAllFilesCheckIndexHeader.java:111)
> >       at
> org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkIndexHeader(TestAllFilesCheckIndexHeader.java:87)
> >       at
> org.apache.lucene.index.TestAllFilesCheckIndexHeader.test(TestAllFilesCheckIndexHeader.java:80)
> >       at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> >       at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> >       at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> >       at java.lang.reflect.Method.invoke(Method.java:606)
> >       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
> 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
> 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 723 lines...]
> >   [junit4] Suite: org.apache.lucene.index.TestAllFilesCheckIndexHeader
> >   [junit4]   2> NOTE: reproduce with: ant test
> -Dtestcase=TestAllFilesCheckIndexHeader -Dtests.method=test
> -Dtests.seed=4BC507D87C083D63 -Dtests.multiplier=2 -Dtests.slow=true
> -Dtests.locale=fi -Dtests.timezone=Etc/GMT+4 -Dtests.asserts=true
> -Dtests.file.encoding=UTF-8
> >   [junit4] ERROR   0.82s J2 | TestAllFilesCheckIndexHeader.test <<<
> >   [junit4]    > Throwable #1: java.io.IOException: file
> "_h_Lucene50_0.doc" was already written to
> >   [junit4]    >       at
> __randomizedtesting.SeedInfo.seed([4BC507D87C083D63:C3913802D2F4509B]:0)
> >   [junit4]    >       at
> org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:558)
> >   [junit4]    >       at
> org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkOneFile(TestAllFilesCheckIndexHeader.java:111)
> >   [junit4]    >       at
> org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkIndexHeader(TestAllFilesCheckIndexHeader.java:87)
> >   [junit4]    >       at
> org.apache.lucene.index.TestAllFilesCheckIndexHeader.test(TestAllFilesCheckIndexHeader.java:80)
> >   [junit4]    >       at java.lang.Thread.run(Thread.java:745)
> >   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54): {},
> docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=yes): {body=IB
> LL-DZ(0.3), titleTokenized=DFR I(n)B3(800.0)}, locale=fi, timezone=Etc/GMT+4
> >   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
> 1.7.0_80 (64-bit)/cpus=4,threads=1,free=131735736,total=350224384
> >   [junit4]   2> NOTE: All tests run in this JVM:
> [TestCachingTokenFilter, TestSpanNearQuery, TestNoDeletionPolicy,
> TestNumericRangeQuery32, TestReaderWrapperDVTypeCheck,
> TestTimSorterWorstCase, TestAutomatonQuery, TestDocValuesRewriteMethod,
> TestMathUtil, TestLazyProxSkipping, TestTransactions,
> FuzzyTermOnShortTermsTest, TestBlockPostingsFormat, TestSumDocFreq,
> TestUnicodeUtil, TestNumericTokenStream, TestTopDocsMerge,
> TestRegexpRandom, TestCharFilter, TestFilterDirectoryReader, TestStressNRT,
> TestOmitTf, TestLucene50TermVectorsFormat, TestSpanTermQuery,
> TestPrefixQuery, TestBooleanCoord, TestPrefixInBooleanQuery,
> TestCompiledAutomaton, Test2BNumericDocValues, TestIndexWriterUnicode,
> TestForUtil, TestConcurrentMergeScheduler, TestMultiTermQueryRewrites,
> TestSloppyPhraseQuery2, TestFilteredSearch, TestSearchWithThreads,
> TestPerFieldPostingsFormat2, TestIndexWriterReader,
> TestBooleanQueryVisitSubscorers, TestTermVectorsWriter, TestMaxPosition,
> TestAllFilesCheckIndexHeader]
> >   [junit4] Completed [160/423 (1!)] on J2 in 0.84s, 1 test, 1 error <<<
> FAILURES!
> >
> > [...truncated 848 lines...]
> > BUILD FAILED
> > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:750:
> The following error occurred while executing this line:
> > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:694:
> The following error occurred while executing this line:
> > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:59:
> The following error occurred while executing this line:
> >
> /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/build.xml:50:
> The following error occurred while executing this line:
> >
> /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/common-build.xml:1477:
> The following error occurred while executing this line:
> >
> /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/common-build.xml:1033:
> There were test failures: 423 suites (1 ignored), 3466 tests, 1 error, 49
> ignored (45 assumptions) [seed: 4BC507D87C083D63]
> >
> > Total time: 7 minutes 28 seconds
> > Build step 'Invoke Ant' marked build as failure
> > Archiving artifacts
> > Recording test results
> > Email was triggered for: Failure - Any
> > Sending email for trigger: Failure - Any
> >
> >
> >
> >
> > ---------------------------------------------------------------------
> > To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> > For additional commands, e-mail: dev-help@lucene.apache.org
>
>

Re: [JENKINS] Lucene-Solr-Tests-5.5 - Build # 26 - Still Failing

Posted by Steve Rowe <sa...@gmail.com>.
This failure reproduces for me on branch_5_5, but not on master or branch_6x.

--
Steve
www.lucidworks.com

> On Feb 13, 2017, at 9:03 PM, Apache Jenkins Server <je...@builds.apache.org> wrote:
> 
> Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.5/26/
> 
> 1 tests failed.
> FAILED:  org.apache.lucene.index.TestAllFilesCheckIndexHeader.test
> 
> Error Message:
> file "_h_Lucene50_0.doc" was already written to
> 
> Stack Trace:
> java.io.IOException: file "_h_Lucene50_0.doc" was already written to
> 	at __randomizedtesting.SeedInfo.seed([4BC507D87C083D63:C3913802D2F4509B]:0)
> 	at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:558)
> 	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkOneFile(TestAllFilesCheckIndexHeader.java:111)
> 	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkIndexHeader(TestAllFilesCheckIndexHeader.java:87)
> 	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.test(TestAllFilesCheckIndexHeader.java:80)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 	at java.lang.reflect.Method.invoke(Method.java:606)
> 	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 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 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 723 lines...]
>   [junit4] Suite: org.apache.lucene.index.TestAllFilesCheckIndexHeader
>   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestAllFilesCheckIndexHeader -Dtests.method=test -Dtests.seed=4BC507D87C083D63 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=fi -Dtests.timezone=Etc/GMT+4 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
>   [junit4] ERROR   0.82s J2 | TestAllFilesCheckIndexHeader.test <<<
>   [junit4]    > Throwable #1: java.io.IOException: file "_h_Lucene50_0.doc" was already written to
>   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([4BC507D87C083D63:C3913802D2F4509B]:0)
>   [junit4]    > 	at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:558)
>   [junit4]    > 	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkOneFile(TestAllFilesCheckIndexHeader.java:111)
>   [junit4]    > 	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkIndexHeader(TestAllFilesCheckIndexHeader.java:87)
>   [junit4]    > 	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.test(TestAllFilesCheckIndexHeader.java:80)
>   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
>   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54): {}, docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=yes): {body=IB LL-DZ(0.3), titleTokenized=DFR I(n)B3(800.0)}, locale=fi, timezone=Etc/GMT+4
>   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=131735736,total=350224384
>   [junit4]   2> NOTE: All tests run in this JVM: [TestCachingTokenFilter, TestSpanNearQuery, TestNoDeletionPolicy, TestNumericRangeQuery32, TestReaderWrapperDVTypeCheck, TestTimSorterWorstCase, TestAutomatonQuery, TestDocValuesRewriteMethod, TestMathUtil, TestLazyProxSkipping, TestTransactions, FuzzyTermOnShortTermsTest, TestBlockPostingsFormat, TestSumDocFreq, TestUnicodeUtil, TestNumericTokenStream, TestTopDocsMerge, TestRegexpRandom, TestCharFilter, TestFilterDirectoryReader, TestStressNRT, TestOmitTf, TestLucene50TermVectorsFormat, TestSpanTermQuery, TestPrefixQuery, TestBooleanCoord, TestPrefixInBooleanQuery, TestCompiledAutomaton, Test2BNumericDocValues, TestIndexWriterUnicode, TestForUtil, TestConcurrentMergeScheduler, TestMultiTermQueryRewrites, TestSloppyPhraseQuery2, TestFilteredSearch, TestSearchWithThreads, TestPerFieldPostingsFormat2, TestIndexWriterReader, TestBooleanQueryVisitSubscorers, TestTermVectorsWriter, TestMaxPosition, TestAllFilesCheckIndexHeader]
>   [junit4] Completed [160/423 (1!)] on J2 in 0.84s, 1 test, 1 error <<< FAILURES!
> 
> [...truncated 848 lines...]
> BUILD FAILED
> /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:750: The following error occurred while executing this line:
> /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:694: The following error occurred while executing this line:
> /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:59: The following error occurred while executing this line:
> /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/build.xml:50: The following error occurred while executing this line:
> /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/common-build.xml:1477: The following error occurred while executing this line:
> /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/common-build.xml:1033: There were test failures: 423 suites (1 ignored), 3466 tests, 1 error, 49 ignored (45 assumptions) [seed: 4BC507D87C083D63]
> 
> Total time: 7 minutes 28 seconds
> Build step 'Invoke Ant' marked build as failure
> Archiving artifacts
> Recording test results
> Email was triggered for: Failure - Any
> Sending email for trigger: Failure - Any
> 
> 
> 
> 
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org


---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
For additional commands, e-mail: dev-help@lucene.apache.org


[JENKINS] Lucene-Solr-Tests-5.5 - Build # 26 - Still Failing

Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.5/26/

1 tests failed.
FAILED:  org.apache.lucene.index.TestAllFilesCheckIndexHeader.test

Error Message:
file "_h_Lucene50_0.doc" was already written to

Stack Trace:
java.io.IOException: file "_h_Lucene50_0.doc" was already written to
	at __randomizedtesting.SeedInfo.seed([4BC507D87C083D63:C3913802D2F4509B]:0)
	at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:558)
	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkOneFile(TestAllFilesCheckIndexHeader.java:111)
	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkIndexHeader(TestAllFilesCheckIndexHeader.java:87)
	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.test(TestAllFilesCheckIndexHeader.java:80)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	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 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 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 723 lines...]
   [junit4] Suite: org.apache.lucene.index.TestAllFilesCheckIndexHeader
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestAllFilesCheckIndexHeader -Dtests.method=test -Dtests.seed=4BC507D87C083D63 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=fi -Dtests.timezone=Etc/GMT+4 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.82s J2 | TestAllFilesCheckIndexHeader.test <<<
   [junit4]    > Throwable #1: java.io.IOException: file "_h_Lucene50_0.doc" was already written to
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([4BC507D87C083D63:C3913802D2F4509B]:0)
   [junit4]    > 	at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:558)
   [junit4]    > 	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkOneFile(TestAllFilesCheckIndexHeader.java:111)
   [junit4]    > 	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.checkIndexHeader(TestAllFilesCheckIndexHeader.java:87)
   [junit4]    > 	at org.apache.lucene.index.TestAllFilesCheckIndexHeader.test(TestAllFilesCheckIndexHeader.java:80)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54): {}, docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=yes): {body=IB LL-DZ(0.3), titleTokenized=DFR I(n)B3(800.0)}, locale=fi, timezone=Etc/GMT+4
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=131735736,total=350224384
   [junit4]   2> NOTE: All tests run in this JVM: [TestCachingTokenFilter, TestSpanNearQuery, TestNoDeletionPolicy, TestNumericRangeQuery32, TestReaderWrapperDVTypeCheck, TestTimSorterWorstCase, TestAutomatonQuery, TestDocValuesRewriteMethod, TestMathUtil, TestLazyProxSkipping, TestTransactions, FuzzyTermOnShortTermsTest, TestBlockPostingsFormat, TestSumDocFreq, TestUnicodeUtil, TestNumericTokenStream, TestTopDocsMerge, TestRegexpRandom, TestCharFilter, TestFilterDirectoryReader, TestStressNRT, TestOmitTf, TestLucene50TermVectorsFormat, TestSpanTermQuery, TestPrefixQuery, TestBooleanCoord, TestPrefixInBooleanQuery, TestCompiledAutomaton, Test2BNumericDocValues, TestIndexWriterUnicode, TestForUtil, TestConcurrentMergeScheduler, TestMultiTermQueryRewrites, TestSloppyPhraseQuery2, TestFilteredSearch, TestSearchWithThreads, TestPerFieldPostingsFormat2, TestIndexWriterReader, TestBooleanQueryVisitSubscorers, TestTermVectorsWriter, TestMaxPosition, TestAllFilesCheckIndexHeader]
   [junit4] Completed [160/423 (1!)] on J2 in 0.84s, 1 test, 1 error <<< FAILURES!

[...truncated 848 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:750: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:694: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/build.xml:50: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/common-build.xml:1477: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.5/lucene/common-build.xml:1033: There were test failures: 423 suites (1 ignored), 3466 tests, 1 error, 49 ignored (45 assumptions) [seed: 4BC507D87C083D63]

Total time: 7 minutes 28 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any