Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.2-Windows/29/ Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseG1GC
7 tests failed. FAILED: junit.framework.TestSuite.org.apache.lucene.index.TestCrash Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001 C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001\_d_Lucene50_0.tim: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001\_d_Lucene50_0.tim C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001 Stack Trace: java.io.IOException: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001 C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001\_d_Lucene50_0.tim: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001\_d_Lucene50_0.tim C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001 at __randomizedtesting.SeedInfo.seed([9B716F0D688062CF]:0) at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216) at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43) 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:368) at java.lang.Thread.run(Thread.java:748) FAILED: junit.framework.TestSuite.org.apache.solr.ltr.feature.TestUserTermScoreWithQ Error Message: Unable to delete directory C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.feature.TestUserTermScoreWithQ_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf. Stack Trace: java.io.IOException: Unable to delete directory C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.feature.TestUserTermScoreWithQ_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf. at __randomizedtesting.SeedInfo.seed([E09C41ACC4FA7B24]:0) at org.apache.commons.io.FileUtils.deleteDirectory(FileUtils.java:1581) at org.apache.commons.io.FileUtils.forceDelete(FileUtils.java:2372) at org.apache.commons.io.FileUtils.cleanDirectory(FileUtils.java:1679) at org.apache.commons.io.FileUtils.deleteDirectory(FileUtils.java:1575) at org.apache.commons.io.FileUtils.forceDelete(FileUtils.java:2372) at org.apache.commons.io.FileUtils.cleanDirectory(FileUtils.java:1679) at org.apache.commons.io.FileUtils.deleteDirectory(FileUtils.java:1575) at org.apache.solr.ltr.TestRerankBase.aftertest(TestRerankBase.java:222) at org.apache.solr.ltr.feature.TestUserTermScoreWithQ.after(TestUserTermScoreWithQ.java:53) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:897) 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:368) at java.lang.Thread.run(Thread.java:748) FAILED: junit.framework.TestSuite.org.apache.solr.ltr.model.TestDefaultWrapperModel Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1 C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf\_schema_model-store.json: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf\_schema_model-store.json C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001 Stack Trace: java.io.IOException: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1 C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf\_schema_model-store.json: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf\_schema_model-store.json C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001 at __randomizedtesting.SeedInfo.seed([E09C41ACC4FA7B24]:0) at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216) at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43) 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:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest.test Error Message: shard1 is not consistent. Got 680 from http://127.0.0.1:56070/bm/collection1_shard1_replica_n43 (previous client) and got 1147 from http://127.0.0.1:56100/bm/collection1_shard1_replica_n47 Stack Trace: java.lang.AssertionError: shard1 is not consistent. Got 680 from http://127.0.0.1:56070/bm/collection1_shard1_replica_n43 (previous client) and got 1147 from http://127.0.0.1:56100/bm/collection1_shard1_replica_n47 at __randomizedtesting.SeedInfo.seed([E68C138005B0C606:6ED82C5AAB4CABFE]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1322) at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest.test(ChaosMonkeyNothingIsSafeWithPullReplicasTest.java:274) 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:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) 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:368) at java.lang.Thread.run(Thread.java:748) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest Error Message: 4 threads leaked from SUITE scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest: 1) Thread[id=20634, name=Connection evictor, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 2) Thread[id=20636, name=TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]-EventThread, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:501) 3) Thread[id=20637, name=zkConnectionManagerCallback-5084-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 4) Thread[id=20635, name=TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]-SendThread(127.0.0.1:55977), state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method) at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:296) at sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:278) at sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:159) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 4 threads leaked from SUITE scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest: 1) Thread[id=20634, name=Connection evictor, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 2) Thread[id=20636, name=TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]-EventThread, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:501) 3) Thread[id=20637, name=zkConnectionManagerCallback-5084-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 4) Thread[id=20635, name=TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]-SendThread(127.0.0.1:55977), state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method) at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:296) at sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:278) at sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:159) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141) at __randomizedtesting.SeedInfo.seed([E68C138005B0C606]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=20637, name=zkConnectionManagerCallback-5084-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 2) Thread[id=20635, name=TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]-SendThread(127.0.0.1:55977), state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:997) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1060) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=20637, name=zkConnectionManagerCallback-5084-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 2) Thread[id=20635, name=TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]-SendThread(127.0.0.1:55977), state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeWithPullReplicasTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101) at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:997) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1060) at __randomizedtesting.SeedInfo.seed([E68C138005B0C606]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ClusterStateTest Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ClusterStateTest_E68C138005B0C606-001\init-core-data-001: java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ClusterStateTest_E68C138005B0C606-001\init-core-data-001 Stack Trace: java.io.IOException: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ClusterStateTest_E68C138005B0C606-001\init-core-data-001: java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ClusterStateTest_E68C138005B0C606-001\init-core-data-001 at __randomizedtesting.SeedInfo.seed([E68C138005B0C606]:0) at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216) at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43) 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:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 1004 lines...] [junit4] Suite: org.apache.lucene.index.TestCrash [junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=4, maxDocsPerChunk=129, blockSize=697), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=4, blockSize=697)), sim=RandomSimilarity(queryNorm=true): {id=DFR I(ne)LZ(0.3), content=DFR I(F)B1}, locale=en-CA, timezone=Europe/Skopje [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=178938888,total=462422016 [junit4] 2> NOTE: All tests run in this JVM: [TestStringHelper, TestNeverDelete, TestIntroSorter, TestCollectionUtil, TestBooleanSimilarity, TestRamUsageEstimator, TestBinaryDocValuesUpdates, TestSpanOrQuery, TestSnapshotDeletionPolicy, TestThreadedForceMerge, TestSearchWithThreads, TestIndexWriterOnVMError, TestAllFilesHaveCodecHeader, TestDirectPacked, TestAllFilesDetectTruncation, TestSpanExplanationsOfNonMatches, TestSimilarity2, TestIndexOrDocValuesQuery, TestParallelLeafReader, TestTimeLimitingCollector, TestMatchNoDocsQuery, TestDateSort, TestSpanCollection, TestPositionIncrement, TestDeterminizeLexicon, TestIndexWriterMergePolicy, TestFSTs, Test4GBStoredFields, TestPriorityQueue, TestSimpleAttributeImpl, TestMatchAllDocsQuery, TestIndexableField, TestIndexSorting, TestFlushByRamOrCountsPolicy, TestSwappedIndexFiles, TestDocsWithFieldSet, TestGeoEncodingUtils, TestLazyProxSkipping, TestIndexFileDeleter, TestSparseFixedBitDocIdSet, TestMultiMMap, TestIndexInput, TestHighCompressionMode, TestTrackingDirectoryWrapper, TestMinimize, TestSimpleExplanationsOfNonMatches, TestSameTokenSamePosition, TestFieldInvertState, TestSimilarityProvider, TestSpanSearchEquivalence, TestSegmentCacheables, TestTerms, TestIndexWriterExceptions, TestSpans, TestIndexManyDocuments, TestSpansEnum, TestRegExp, TestReusableStringReader, TestIndexWriterThreadsToSegments, TestByteArrayDataInput, TestSingleInstanceLockFactory, TestBlockPostingsFormat2, TestComplexExplanations, TestAutomaton, TestMergePolicyWrapper, TestLucene70DocValuesFormat, TestMathUtil, TestStandardAnalyzer, TestHugeRamFile, TestPrefixQuery, TestAtomicUpdate, TestSynonymQuery, TestIntBlockPool, TestRAMDirectory, TestMultiset, TestBoostQuery, TestNorms, TestAddIndexes, TestUnicodeUtil, TestControlledRealTimeReopenThread, TestSortRescorer, TestCharFilter, TestFilterSpans, TestForceMergeForever, TestCodecs, TestSegmentReader, TestParallelCompositeReader, TestFilterLeafReader, TestDocValuesIndexing, TestUTF32ToUTF8, TestIntRangeFieldQueries, TestPrefixRandom, TestToken, TestNewestSegment, TestCrash] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCrash -Dtests.seed=9B716F0D688062CF -Dtests.slow=true -Dtests.locale=en-CA -Dtests.timezone=Europe/Skopje -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 0.00s J0 | TestCrash (suite) <<< [junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts): [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001 [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001\_d_Lucene50_0.tim: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001\index-NIOFSDirectory-001\_d_Lucene50_0.tim [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J0\temp\lucene.index.TestCrash_9B716F0D688062CF-001 [junit4] > at __randomizedtesting.SeedInfo.seed([9B716F0D688062CF]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [234/458 (1!)] on J0 in 1.09s, 5 tests, 1 error <<< FAILURES! [...truncated 11404 lines...] [junit4] Suite: org.apache.solr.cloud.ClusterStateTest [junit4] 2> 978492 INFO (SUITE-ClusterStateTest-seed#[E68C138005B0C606]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ClusterStateTest_E68C138005B0C606-001\init-core-data-001 [junit4] 2> 978494 WARN (SUITE-ClusterStateTest-seed#[E68C138005B0C606]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3 [junit4] 2> 978494 INFO (SUITE-ClusterStateTest-seed#[E68C138005B0C606]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 978510 INFO (SUITE-ClusterStateTest-seed#[E68C138005B0C606]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 978513 INFO (TEST-ClusterStateTest.testStoreAndRead-seed#[E68C138005B0C606]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStoreAndRead [junit4] 2> 978514 INFO (TEST-ClusterStateTest.testStoreAndRead-seed#[E68C138005B0C606]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStoreAndRead [junit4] 2> 978515 INFO (SUITE-ClusterStateTest-seed#[E68C138005B0C606]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1148, maxMBSortInHeap=6.0570201675329445, sim=RandomSimilarity(queryNorm=false): {}, locale=hi-IN, timezone=America/Rosario [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=128363480,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [DistributedFacetPivotSmallAdvancedTest, AlternateDirectoryTest, TestRangeQuery, TestQuerySenderListener, TestCloudDeleteByQuery, TestExactStatsCache, SystemLogListenerTest, MultiTermTest, VMParamsZkACLAndCredentialsProvidersTest, TestStressInPlaceUpdates, SpatialFilterTest, LeaderElectionTest, TestBinaryResponseWriter, MigrateRouteKeyTest, StatsComponentTest, SaslZkACLProviderTest, TestRecoveryHdfs, PrimUtilsTest, RequiredFieldsTest, TestLegacyNumericRangeQueryBuilder, DocValuesMissingTest, ImplicitSnitchTest, TestRequestStatusCollectionAPI, TimeZoneUtilsTest, NotRequiredUniqueKeyTest, TestFieldCacheSort, ConnectionReuseTest, TestSolrCloudWithSecureImpersonation, BasicAuthIntegrationTest, TestImplicitCoreProperties, ConfigureRecoveryStrategyTest, TestTestInjection, TestEmbeddedSolrServerAdminHandler, SyncSliceTest, SubstringBytesRefFilterTest, SuggestComponentTest, NoCacheHeaderTest, TestOmitPositions, TestFuzzyAnalyzedSuggestions, ShowFileRequestHandlerTest, TestCoreDiscovery, MoveReplicaTest, TestRandomRequestDistribution, TestPointFields, TestReplicaProperties, TestDynamicFieldCollectionResource, SpatialHeatmapFacetsTest, TestLegacyFieldReuse, TestLegacyFieldCache, TestMaxTokenLenTokenizer, SecurityConfHandlerTest, SolrCLIZkUtilsTest, CollectionReloadTest, SolrCoreMetricManagerTest, DateFieldTest, TestReloadAndDeleteDocs, SignatureUpdateProcessorFactoryTest, TestCodecSupport, TestReplicationHandler, TestFieldCache, TestBlendedInfixSuggestions, BJQParserTest, TestSubQueryTransformer, TestUseDocValuesAsStored2, TestSha256AuthenticationProvider, TestPushWriter, RulesTest, DeleteInactiveReplicaTest, TestIndexingPerformance, SpellCheckComponentTest, SliceStateTest, HdfsThreadLeakTest, NodeLostTriggerTest, HdfsDirectoryFactoryTest, RollingRestartTest, AutoScalingHandlerTest, UUIDFieldTest, TestSolr4Spatial, TestClassicSimilarityFactory, ExplicitHLLTest, SolrJmxReporterCloudTest, SolrRequestParserTest, TestOrdValues, TestCloudPseudoReturnFields, TestNonDefinedSimilarityFactory, TestTolerantSearch, TestConfigSets, TestSubQueryTransformerDistrib, ChaosMonkeyNothingIsSafeTest, ChaosMonkeySafeLeaderWithPullReplicasTest, DistributedQueryElevationComponentTest, JSONWriterTest, TestNamedUpdateProcessors, CoreAdminCreateDiscoverTest, DistributedFacetPivotLargeTest, TestScoreJoinQPScore, ShardRoutingTest, SearchHandlerTest, HdfsBasicDistributedZk2Test, TestLegacyTerms, SolrInfoBeanTest, HdfsCollectionsAPIDistributedZkTest, CoreMergeIndexesAdminHandlerTest, OverseerStatusTest, ClusterStateTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ClusterStateTest -Dtests.seed=E68C138005B0C606 -Dtests.slow=true -Dtests.locale=hi-IN -Dtests.timezone=America/Rosario -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 0.00s J0 | ClusterStateTest (suite) <<< [junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts): [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ClusterStateTest_E68C138005B0C606-001\init-core-data-001: java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ClusterStateTest_E68C138005B0C606-001\init-core-data-001 [junit4] > at __randomizedtesting.SeedInfo.seed([E68C138005B0C606]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [251/751 (1!)] on J0 in 0.06s, 1 test, 1 error <<< FAILURES! [...truncated 864 lines...] [junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\init-core-data-001 [junit4] 2> 1929795 WARN (SUITE-ChaosMonkeyNothingIsSafeWithPullReplicasTest-seed#[E68C138005B0C606]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4 [junit4] 2> 1929795 INFO (SUITE-ChaosMonkeyNothingIsSafeWithPullReplicasTest-seed#[E68C138005B0C606]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 1929799 INFO (SUITE-ChaosMonkeyNothingIsSafeWithPullReplicasTest-seed#[E68C138005B0C606]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776) [junit4] 2> 1929799 INFO (SUITE-ChaosMonkeyNothingIsSafeWithPullReplicasTest-seed#[E68C138005B0C606]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1929800 INFO (SUITE-ChaosMonkeyNothingIsSafeWithPullReplicasTest-seed#[E68C138005B0C606]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /bm/ [junit4] 2> 1929800 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.ChaosMonkeyNothingIsSafeWithPullReplicasTest Starting ChaosMonkey test with 2 shards and 6 nodes [junit4] 2> 1929805 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1929805 INFO (Thread-3563) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1929805 INFO (Thread-3563) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1929810 ERROR (Thread-3563) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 1929905 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.ZkTestServer start zk server on port:55977 [junit4] 2> 1929919 INFO (zkConnectionManagerCallback-5064-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1929942 INFO (zkConnectionManagerCallback-5066-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1929955 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1929958 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf\schema15.xml to /configs/conf1/schema.xml [junit4] 2> 1929960 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1929962 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1929964 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1929966 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4] 2> 1929967 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1929969 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1929971 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1929972 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1929973 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1929975 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 0x160866ad7ff0001, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 1929975 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise [junit4] 2> 1930487 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 1930487 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7a18d789{/bm,null,AVAILABLE} [junit4] 2> 1930489 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@78403774{HTTP/1.1,[http/1.1]}{127.0.0.1:55984} [junit4] 2> 1930490 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.Server Started @1942004ms [junit4] 2> 1930490 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\tempDir-001/control/data, hostContext=/bm, hostPort=55984, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\control-001\cores} [junit4] 2> 1930491 ERROR (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1930491 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.1 [junit4] 2> 1930491 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1930491 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1930491 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-24T02:47:17.928Z [junit4] 2> 1930494 INFO (zkConnectionManagerCallback-5068-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1930495 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1930495 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\control-001\solr.xml [junit4] 2> 1930504 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1930504 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1930505 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@69ae733b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1930509 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55977/solr [junit4] 2> 1930511 INFO (zkConnectionManagerCallback-5072-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1930514 INFO (zkConnectionManagerCallback-5074-thread-1-processing-n:127.0.0.1:55984_bm) [n:127.0.0.1:55984_bm ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1930644 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:55984_bm ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1930645 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:55984_bm ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55984_bm [junit4] 2> 1930646 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:55984_bm ] o.a.s.c.Overseer Overseer (id=99226985250619396-127.0.0.1:55984_bm-n_0000000000) starting [junit4] 2> 1930655 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:55984_bm ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55984_bm [junit4] 2> 1930656 INFO (OverseerStateUpdate-99226985250619396-127.0.0.1:55984_bm-n_0000000000) [n:127.0.0.1:55984_bm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1930778 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:55984_bm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69ae733b [junit4] 2> 1930785 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:55984_bm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69ae733b [junit4] 2> 1930785 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:55984_bm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69ae733b [junit4] 2> 1930787 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:55984_bm ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\control-001\cores [junit4] 2> 1930814 INFO (zkConnectionManagerCallback-5080-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1930815 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1930816 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55977/solr ready [junit4] 2> 1930824 INFO (qtp1408267810-20593) [n:127.0.0.1:55984_bm ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:55984_bm&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1930827 INFO (OverseerThreadFactory-8862-thread-1-processing-n:127.0.0.1:55984_bm) [n:127.0.0.1:55984_bm ] o.a.s.c.CreateCollectionCmd Create collection control_collection [junit4] 2> 1930934 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1930935 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1931042 INFO (zkCallback-5073-thread-1-processing-n:127.0.0.1:55984_bm) [n:127.0.0.1:55984_bm ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 1931964 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.1 [junit4] 2> 1931974 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test [junit4] 2> 1932038 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 1932054 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true [junit4] 2> 1932055 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69ae733b [junit4] 2> 1932055 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1932055 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\control-001\cores\control_collection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\control-001\cores\control_collection_shard1_replica_n1\data\] [junit4] 2> 1932063 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=58.5390625, floorSegmentMB=1.146484375, forceMergeDeletesPctAllowed=9.561370780743294, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1932068 WARN (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1932151 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1932151 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1932152 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 1932152 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1932153 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=98.4482421875, floorSegmentMB=1.423828125, forceMergeDeletesPctAllowed=25.981884183778533, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10933007424177427 [junit4] 2> 1932154 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@577f385d[control_collection_shard1_replica_n1] main] [junit4] 2> 1932155 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1932155 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1932157 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1932158 INFO (searcherExecutor-8865-thread-1-processing-n:127.0.0.1:55984_bm x:control_collection_shard1_replica_n1 s:shard1 c:control_collection) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@577f385d[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1932158 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1587631766471966720 [junit4] 2> 1932162 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1932163 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1932163 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55984/bm/control_collection_shard1_replica_n1/ [junit4] 2> 1932163 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1932163 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:55984/bm/control_collection_shard1_replica_n1/ has no replicas [junit4] 2> 1932163 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1932166 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:55984/bm/control_collection_shard1_replica_n1/ shard1 [junit4] 2> 1932267 INFO (zkCallback-5073-thread-2-processing-n:127.0.0.1:55984_bm) [n:127.0.0.1:55984_bm ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 1932319 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1932322 INFO (qtp1408267810-20598) [n:127.0.0.1:55984_bm c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1388 [junit4] 2> 1932329 INFO (qtp1408267810-20593) [n:127.0.0.1:55984_bm ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 1932421 INFO (zkCallback-5073-thread-2-processing-n:127.0.0.1:55984_bm) [n:127.0.0.1:55984_bm ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 1932829 INFO (OverseerCollectionConfigSetProcessor-99226985250619396-127.0.0.1:55984_bm-n_0000000000) [n:127.0.0.1:55984_bm ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1933330 INFO (qtp1408267810-20593) [n:127.0.0.1:55984_bm ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:55984_bm&wt=javabin&version=2} status=0 QTime=2504 [junit4] 2> 1933340 INFO (zkConnectionManagerCallback-5084-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1933343 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1933343 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55977/solr ready [junit4] 2> 1933344 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1933345 INFO (qtp1408267810-20595) [n:127.0.0.1:55984_bm ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1933347 INFO (OverseerThreadFactory-8862-thread-2-processing-n:127.0.0.1:55984_bm) [n:127.0.0.1:55984_bm ] o.a.s.c.CreateCollectionCmd Create collection collection1 [junit4] 2> 1933348 WARN (OverseerThreadFactory-8862-thread-2-processing-n:127.0.0.1:55984_bm) [n:127.0.0.1:55984_bm ] o.a.s.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores. [junit4] 2> 1933555 INFO (qtp1408267810-20595) [n:127.0.0.1:55984_bm ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 1933556 INFO (qtp1408267810-20595) [n:127.0.0.1:55984_bm ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2} status=0 QTime=211 [junit4] 2> 1934059 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\shard-1-001 of type NRT [junit4] 2> 1934059 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 1934060 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13a0b10d{/bm,null,AVAILABLE} [junit4] 2> 1934060 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4858b535{HTTP/1.1,[http/1.1]}{127.0.0.1:56041} [junit4] 2> 1934062 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.Server Started @1945576ms [junit4] 2> 1934062 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\tempDir-001/jetty1, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/bm, hostPort=56041, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\shard-1-001\cores} [junit4] 2> 1934062 ERROR (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1934062 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.1 [junit4] 2> 1934062 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1934064 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1934064 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-24T02:47:21.501Z [junit4] 2> 1934066 INFO (zkConnectionManagerCallback-5086-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1934067 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1934067 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\shard-1-001\solr.xml [junit4] 2> 1934076 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1934076 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1934077 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@69ae733b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1934081 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55977/solr [junit4] 2> 1934083 INFO (zkConnectionManagerCallback-5090-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1934087 INFO (zkConnectionManagerCallback-5092-thread-1-processing-n:127.0.0.1:56041_bm) [n:127.0.0.1:56041_bm ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1934093 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:56041_bm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1934096 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:56041_bm ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1934098 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:56041_bm ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56041_bm [junit4] 2> 1934099 INFO (zkCallback-5073-thread-2-processing-n:127.0.0.1:55984_bm) [n:127.0.0.1:55984_bm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1934100 INFO (zkCallback-5091-thread-1-processing-n:127.0.0.1:56041_bm) [n:127.0.0.1:56041_bm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1934100 INFO (zkCallback-5083-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1934220 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:56041_bm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69ae733b [junit4] 2> 1934230 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:56041_bm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69ae733b [junit4] 2> 1934230 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:56041_bm ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69ae733b [junit4] 2> 1934234 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:56041_bm ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\shard-1-001\cores [junit4] 2> 1934275 INFO (qtp1408267810-20591) [n:127.0.0.1:55984_bm ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:56041_bm&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1934280 INFO (OverseerCollectionConfigSetProcessor-99226985250619396-127.0.0.1:55984_bm-n_0000000000) [n:127.0.0.1:55984_bm ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1934282 INFO (OverseerThreadFactory-8862-thread-3-processing-n:127.0.0.1:55984_bm) [n:127.0.0.1:55984_bm ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:56041_bm for creating new replica [junit4] 2> 1934294 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1934295 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1934407 INFO (zkCallback-5091-thread-1-processing-n:127.0.0.1:56041_bm) [n:127.0.0.1:56041_bm ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1935324 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.1 [junit4] 2> 1935333 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema [collection1_shard2_replica_n41] Schema name=test [junit4] 2> 1935409 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 1935432 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n41' using configuration from collection collection1, trusted=true [junit4] 2> 1935433 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n41' (registry 'solr.core.collection1.shard2.replica_n41') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69ae733b [junit4] 2> 1935433 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1935433 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore [[collection1_shard2_replica_n41] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\shard-1-001\cores\collection1_shard2_replica_n41], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\shard-1-001\cores\collection1_shard2_replica_n41\data\] [junit4] 2> 1935441 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=58.5390625, floorSegmentMB=1.146484375, forceMergeDeletesPctAllowed=9.561370780743294, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1935450 WARN (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1935558 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1935558 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1935561 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; [junit4] 2> 1935561 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1935562 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=98.4482421875, floorSegmentMB=1.423828125, forceMergeDeletesPctAllowed=25.981884183778533, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10933007424177427 [junit4] 2> 1935563 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e369ad5[collection1_shard2_replica_n41] main] [junit4] 2> 1935565 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1935565 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1935566 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1935568 INFO (searcherExecutor-8876-thread-1-processing-n:127.0.0.1:56041_bm x:collection1_shard2_replica_n41 s:shard2 c:collection1) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore [collection1_shard2_replica_n41] Registered new searcher Searcher@2e369ad5[collection1_shard2_replica_n41] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1935568 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1587631770047610880 [junit4] 2> 1935576 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1935576 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1935576 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56041/bm/collection1_shard2_replica_n41/ [junit4] 2> 1935576 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1935576 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy http://127.0.0.1:56041/bm/collection1_shard2_replica_n41/ has no replicas [junit4] 2> 1935576 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1935580 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:56041/bm/collection1_shard2_replica_n41/ shard2 [junit4] 2> 1935683 INFO (zkCallback-5091-thread-1-processing-n:127.0.0.1:56041_bm) [n:127.0.0.1:56041_bm ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1935733 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1935736 INFO (qtp337585998-20641) [n:127.0.0.1:56041_bm c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1442 [junit4] 2> 1935738 INFO (qtp1408267810-20591) [n:127.0.0.1:55984_bm ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:56041_bm&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2} status=0 QTime=1463 [junit4] 2> 1935845 INFO (zkCallback-5091-thread-1-processing-n:127.0.0.1:56041_bm) [n:127.0.0.1:56041_bm ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1936281 INFO (OverseerCollectionConfigSetProcessor-99226985250619396-127.0.0.1:55984_bm-n_0000000000) [n:127.0.0.1:55984_bm ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1936366 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\shard-2-001 of type NRT [junit4] 2> 1936367 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 1936368 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@309a8b93{/bm,null,AVAILABLE} [junit4] 2> 1936368 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@47ea9477{HTTP/1.1,[http/1.1]}{127.0.0.1:56070} [junit4] 2> 1936368 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.e.j.s.Server Started @1947882ms [junit4] 2> 1936372 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\tempDir-001/jetty2, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/bm, hostPort=56070, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\shard-2-001\cores} [junit4] 2> 1936372 ERROR (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1936373 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.1 [junit4] 2> 1936373 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1936373 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1936373 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-24T02:47:23.810Z [junit4] 2> 1936376 INFO (zkConnectionManagerCallback-5095-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1936378 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1936378 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeWithPullReplicasTest_E68C138005B0C606-001\shard-2-001\solr.xml [junit4] 2> 1936388 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1936388 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1936389 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@69ae733b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1936395 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55977/solr [junit4] 2> 1936398 INFO (zkConnectionManagerCallback-5099-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1936401 INFO (zkConnectionManagerCallback-5101-thread-1-processing-n:127.0.0.1:56070_bm) [n:127.0.0.1:56070_bm ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1936408 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:56070_bm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1936410 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:56070_bm ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1936412 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-seed#[E68C138005B0C606]) [n:127.0.0.1:56070_bm ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56070_bm [junit4] 2> 1936413 INFO (zkCallback-5091-thread-1-processing-n:127.0.0.1:56041_bm) [n:127.0.0.1:56041_bm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1936413 INFO (zkCallback-5073-thread-2-processing-n:127.0.0.1:55984_bm) [n:127.0.0.1:55984_bm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1936415 INFO (zkCallback-5083-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1936415 INFO (zkCallback-5100-thread-1-processing-n:127.0.0.1:56070_bm) [n:127.0.0.1:56070_bm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1936511 INFO (TEST-ChaosMonkeyNothingIsSafeWithPullReplicasTest.test-se [...truncated too long message...] ceConsume(ExecuteProduceConsume.java:303) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 37218 INFO (TEST-TestDefaultWrapperModel.testLoadModelWithEmptyParams-seed#[E09C41ACC4FA7B24]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLoadModelWithEmptyParams [junit4] 2> 37224 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@491b5{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 37224 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2019425656 [junit4] 2> 37224 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 37224 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2677a70d: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3308c41e [junit4] 2> 37230 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 37230 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@76154862: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@12a07b9e [junit4] 2> 37233 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 37233 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@61344455: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5ab63e30 [junit4] 2> 37242 INFO (coreCloseExecutor-158-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@11e0f6d5 [junit4] 2> 37242 INFO (coreCloseExecutor-158-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=299955925 [junit4] 2> 37242 INFO (coreCloseExecutor-158-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@720809e5: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@12e580ce [junit4] 2> 37277 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@238b8d93{/solr,null,UNAVAILABLE} [junit4] 2> 37280 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 37280 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=760993521 [junit4] 2> 37280 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 37281 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b921ec1: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@53132ba3 [junit4] 2> 37281 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 37282 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3ed8b880: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@12a07b9e [junit4] 2> 37282 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 37282 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@73f439ce: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5ab63e30 [junit4] 2> 37286 INFO (coreCloseExecutor-160-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2257217e [junit4] 2> 37286 INFO (coreCloseExecutor-160-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=576135550 [junit4] 2> 37286 INFO (coreCloseExecutor-160-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6f93e219: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@223a4c6e [junit4] 2> 37295 INFO (SUITE-TestDefaultWrapperModel-seed#[E09C41ACC4FA7B24]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=2, maxDocsPerChunk=712, blockSize=774), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=2, blockSize=774)), sim=RandomSimilarity(queryNorm=false): {}, locale=ro-RO, timezone=Asia/Thimphu [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=20400104,total=100663296 [junit4] 2> NOTE: All tests run in this JVM: [TestLTROnSolrCloud, TestLTRWithSort, TestUserTermScoreWithQ, TestLTRScoringQuery, TestEdisMaxSolrFeature, TestFieldValueFeature, TestDefaultWrapperModel] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDefaultWrapperModel -Dtests.seed=E09C41ACC4FA7B24 -Dtests.slow=true -Dtests.locale=ro-RO -Dtests.timezone=Asia/Thimphu -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J0 | TestDefaultWrapperModel (suite) <<< [junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts): [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1 [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001 [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf\_schema_model-store.json: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001\tempDir-001\collection1\conf\_schema_model-store.json [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\contrib\solr-ltr\test\J0\temp\solr.ltr.model.TestDefaultWrapperModel_E09C41ACC4FA7B24-001 [junit4] > at __randomizedtesting.SeedInfo.seed([E09C41ACC4FA7B24]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [18/35 (2!)] on J0 in 2.42s, 4 tests, 1 error <<< FAILURES! [...truncated 40118 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org