Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6924/ Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseSerialGC
4 tests failed. FAILED: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.test Error Message: Timeout occured while waiting response from server at: http://127.0.0.1:55019/nm/p/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:55019/nm/p/collection1 at __randomizedtesting.SeedInfo.seed([E17FA859CCCA9C52:692B97836236F1AA]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:638) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1121) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178) at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:484) at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:463) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.commit(AbstractFullDistribZkTestBase.java:1583) at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.test(ChaosMonkeyNothingIsSafeTest.java:213) 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) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:171) at java.net.SocketInputStream.read(SocketInputStream.java:141) at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137) at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153) at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:526) ... 53 more FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest Error Message: 9 threads leaked from SUITE scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest: 1) Thread[id=19057, name=zkCallback-2419-thread-5, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=19055, name=zkCallback-2419-thread-3, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 3) Thread[id=19080, name=zkCallback-2419-thread-6, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=18916, name=Connection evictor, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] 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) 5) Thread[id=19054, name=zkCallback-2419-thread-2, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 6) Thread[id=18919, name=zkCallback-2419-thread-1, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 7) Thread[id=19056, name=zkCallback-2419-thread-4, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 8) Thread[id=18918, name=TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]-EventThread, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] 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) 9) Thread[id=18917, name=TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]-SendThread(127.0.0.1:54975), state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] 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: 9 threads leaked from SUITE scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest: 1) Thread[id=19057, name=zkCallback-2419-thread-5, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=19055, name=zkCallback-2419-thread-3, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 3) Thread[id=19080, name=zkCallback-2419-thread-6, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=18916, name=Connection evictor, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] 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) 5) Thread[id=19054, name=zkCallback-2419-thread-2, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 6) Thread[id=18919, name=zkCallback-2419-thread-1, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 7) Thread[id=19056, name=zkCallback-2419-thread-4, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 8) Thread[id=18918, name=TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]-EventThread, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] 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) 9) Thread[id=18917, name=TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]-SendThread(127.0.0.1:54975), state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] 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([E17FA859CCCA9C52]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=19057, name=zkCallback-2419-thread-5, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=19055, name=zkCallback-2419-thread-3, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 3) Thread[id=19080, name=zkCallback-2419-thread-6, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=19054, name=zkCallback-2419-thread-2, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 5) Thread[id=18919, name=zkCallback-2419-thread-1, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 6) Thread[id=19056, name=zkCallback-2419-thread-4, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 7) Thread[id=18917, name=TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]-SendThread(127.0.0.1:54975), state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] 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: There are still zombie threads that couldn't be terminated: 1) Thread[id=19057, name=zkCallback-2419-thread-5, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=19055, name=zkCallback-2419-thread-3, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 3) Thread[id=19080, name=zkCallback-2419-thread-6, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=19054, name=zkCallback-2419-thread-2, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 5) Thread[id=18919, name=zkCallback-2419-thread-1, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 6) Thread[id=19056, name=zkCallback-2419-thread-4, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 7) Thread[id=18917, name=TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]-SendThread(127.0.0.1:54975), state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] 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([E17FA859CCCA9C52]:0) FAILED: junit.framework.TestSuite.org.apache.solr.core.TestSolrIndexConfig Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrIndexConfig_E17FA859CCCA9C52-001\init-core-data-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrIndexConfig_E17FA859CCCA9C52-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-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrIndexConfig_E17FA859CCCA9C52-001\init-core-data-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrIndexConfig_E17FA859CCCA9C52-001\init-core-data-001 at __randomizedtesting.SeedInfo.seed([E17FA859CCCA9C52]: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 12919 lines...] [junit4] Suite: org.apache.solr.core.TestSolrIndexConfig [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrIndexConfig_E17FA859CCCA9C52-001\init-core-data-001 [junit4] 2> 2223633 WARN (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 2223633 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 2223643 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 2223644 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 2223646 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 2223647 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 2223681 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.u.SolrIndexConfig IndexWriter infoStream solr logging is enabled [junit4] 2> 2223681 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2223686 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test [junit4] 2> 2223755 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 2223819 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69a2aca1 [junit4] 2> 2223829 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69a2aca1 [junit4] 2> 2223830 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69a2aca1 [junit4] 2> 2223833 INFO (coreLoadExecutor-8878-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2223833 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 2223869 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.SolrIndexConfig IndexWriter infoStream solr logging is enabled [junit4] 2> 2223869 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2223874 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2223942 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 2223949 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1, trusted=true [junit4] 2> 2223950 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69a2aca1 [junit4] 2> 2223950 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2223950 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrIndexConfig_E17FA859CCCA9C52-001\init-core-data-001\] [junit4] 2> 2223953 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=89.015625, floorSegmentMB=0.498046875, forceMergeDeletesPctAllowed=14.852355589617893, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19881396235114712 [junit4] 2> 2223954 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-8878-thread-1]: init: current segments file is "segments"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1bb0ace1 [junit4] 2> 2223954 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-8878-thread-1]: now checkpoint "" [0 segments ; isCommit = false] [junit4] 2> 2223954 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-8878-thread-1]: 0 msec to checkpoint [junit4] 2> 2223954 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: init: create=true [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: [junit4] 2> dir=NRTCachingDirectory(MMapDirectory@C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrIndexConfig_E17FA859CCCA9C52-001\init-core-data-001\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2ecbd189; maxCacheMB=48.0 maxMergeSizeMB=4.0) [junit4] 2> index= [junit4] 2> version=8.0.0 [junit4] 2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer [junit4] 2> ramBufferSizeMB=100.0 [junit4] 2> maxBufferedDocs=-1 [junit4] 2> mergedSegmentWarmer=null [junit4] 2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper [junit4] 2> commit=null [junit4] 2> openMode=CREATE [junit4] 2> similarity=org.apache.solr.search.similarities.MockConfigurableSimilarity [junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true [junit4] 2> codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=912, maxMBSortInHeap=5.370552358816802 [junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream [junit4] 2> mergePolicy=RandomMergePolicy([TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=89.015625, floorSegmentMB=0.498046875, forceMergeDeletesPctAllowed=14.852355589617893, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19881396235114712) [junit4] 2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@18799b49 [junit4] 2> readerPooling=true [junit4] 2> perThreadHardLimitMB=1945 [junit4] 2> useCompoundFile=true [junit4] 2> commitOnClose=true [junit4] 2> indexSort=null [junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@18b56158 [junit4] 2> [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: now flush at close [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: start flush: applyAllDeletes=true [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: index before flush [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-8878-thread-1]: startFullFlush [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-8878-thread-1]: coreLoadExecutor-8878-thread-1 finishFullFlush success=true [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0 [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-8878-thread-1]: waitApply: no deletes to apply [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-8878-thread-1]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6 [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-8878-thread-1]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec [junit4] 2> 2223955 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-8878-thread-1]: now merge [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-8878-thread-1]: index: [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-8878-thread-1]: no more merges pending; now return [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: waitForMerges [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: waitForMerges done [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: commit: start [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: commit: enter lock [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: commit: now prepare [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: prepareCommit: flush [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: index before flush [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-8878-thread-1]: startFullFlush [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0 [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-8878-thread-1]: waitApply: no deletes to apply [junit4] 2> 2223956 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-8878-thread-1]: coreLoadExecutor-8878-thread-1 finishFullFlush success=true [junit4] 2> 2223957 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: startCommit(): start [junit4] 2> 2223957 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: startCommit index= changeCount=2 [junit4] 2> 2223959 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: startCommit: wrote pending segments file "pending_segments_1" [junit4] 2> 2223959 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: done all syncs: [] [junit4] 2> 2223959 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: commit: pendingCommit != null [junit4] 2> 2223960 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: commit: done writing segments file "segments_1" [junit4] 2> 2223960 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-8878-thread-1]: now checkpoint "" [0 segments ; isCommit = true] [junit4] 2> 2223960 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-8878-thread-1]: 0 msec to checkpoint [junit4] 2> 2223960 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: commit: took 4.7 msec [junit4] 2> 2223960 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: commit: done [junit4] 2> 2223960 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: rollback [junit4] 2> 2223960 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: all running merges have aborted [junit4] 2> 2223960 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: rollback: done finish merges [junit4] 2> 2223960 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-8878-thread-1]: abort [junit4] 2> 2223961 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-8878-thread-1]: done abort success=true [junit4] 2> 2223961 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: rollback: infos= [junit4] 2> 2223961 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-8878-thread-1]: now checkpoint "" [0 segments ; isCommit = false] [junit4] 2> 2223961 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-8878-thread-1]: 0 msec to checkpoint [junit4] 2> 2224018 WARN (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard') [junit4] 2> 2224018 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2224018 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2224019 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-8878-thread-1]: init: current segments file is "segments_1"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@1bb0ace1 [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-8878-thread-1]: init: load commit "segments_1" [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-8878-thread-1]: now checkpoint "" [0 segments ; isCommit = false] [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-8878-thread-1]: 0 msec to checkpoint [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: init: create=false [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: [junit4] 2> dir=NRTCachingDirectory(MMapDirectory@C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrIndexConfig_E17FA859CCCA9C52-001\init-core-data-001\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2ecbd189; maxCacheMB=48.0 maxMergeSizeMB=4.0) [junit4] 2> index= [junit4] 2> version=8.0.0 [junit4] 2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer [junit4] 2> ramBufferSizeMB=100.0 [junit4] 2> maxBufferedDocs=-1 [junit4] 2> mergedSegmentWarmer=null [junit4] 2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper [junit4] 2> commit=null [junit4] 2> openMode=APPEND [junit4] 2> similarity=org.apache.solr.search.similarities.MockConfigurableSimilarity [junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true [junit4] 2> codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=912, maxMBSortInHeap=5.370552358816802 [junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream [junit4] 2> mergePolicy=RandomMergePolicy([LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]) [junit4] 2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@643a6361 [junit4] 2> readerPooling=true [junit4] 2> perThreadHardLimitMB=1945 [junit4] 2> useCompoundFile=true [junit4] 2> commitOnClose=true [junit4] 2> indexSort=null [junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@20a146cf [junit4] 2> [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: MMapDirectory.UNMAP_SUPPORTED=true [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: flush at getReader [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-8878-thread-1]: startFullFlush [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0 [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-8878-thread-1]: waitApply: no deletes to apply [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: incRefDeleter for NRT reader version=2 segments= [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt) [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-8878-thread-1]: coreLoadExecutor-8878-thread-1 finishFullFlush success=true [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-8878-thread-1]: getReader took 0 msec [junit4] 2> 2224020 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@51e67c51[collection1] main] [junit4] 2> 2224021 WARN (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead. [junit4] 2> 2224021 INFO (coreLoadExecutor-8878-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 2224023 INFO (searcherExecutor-8879-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@51e67c51[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2224024 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 2224026 INFO (TEST-TestSolrIndexConfig.testIndexConfigParsing-seed#[E17FA859CCCA9C52]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testIndexConfigParsing [junit4] 2> 2224026 INFO (TEST-TestSolrIndexConfig.testIndexConfigParsing-seed#[E17FA859CCCA9C52]) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6066853054683479] [junit4] 2> 2224026 INFO (TEST-TestSolrIndexConfig.testIndexConfigParsing-seed#[E17FA859CCCA9C52]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testIndexConfigParsing [junit4] 2> 2224027 INFO (TEST-TestSolrIndexConfig.testLiveWriter-seed#[E17FA859CCCA9C52]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLiveWriter [junit4] 2> 2224028 INFO (TEST-TestSolrIndexConfig.testLiveWriter-seed#[E17FA859CCCA9C52]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLiveWriter [junit4] 2> 2224028 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 2224028 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=169855060 [junit4] 2> 2224028 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 2224028 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55371107: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@60325f54 [junit4] 2> 2224030 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 2224030 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2cf99b5f: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@7501b2a9 [junit4] 2> 2224032 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 2224032 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7ae9cb25: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@40f6e2d9 [junit4] 2> 2224033 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@c13caf3 [junit4] 2> 2224033 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=202623731 [junit4] 2> 2224033 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@48ca3afb: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@252bc352 [junit4] 2> 2224043 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: now flush at close [junit4] 2> 2224043 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: start flush: applyAllDeletes=true [junit4] 2> 2224043 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: index before flush [junit4] 2> 2224043 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-8883-thread-1]: startFullFlush [junit4] 2> 2224043 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-8883-thread-1]: coreCloseExecutor-8883-thread-1 finishFullFlush success=true [junit4] 2> 2224044 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0 [junit4] 2> 2224044 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-8883-thread-1]: waitApply: no deletes to apply [junit4] 2> 2224044 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-8883-thread-1]: initDynamicDefaults spins=true maxThreadCount=1 maxMergeCount=6 [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-8883-thread-1]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-8883-thread-1]: now merge [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-8883-thread-1]: index: [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-8883-thread-1]: no more merges pending; now return [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: waitForMerges [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: waitForMerges done [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: commit: start [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: commit: enter lock [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: commit: now prepare [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: prepareCommit: flush [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: index before flush [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-8883-thread-1]: startFullFlush [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0 [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-8883-thread-1]: waitApply: no deletes to apply [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-8883-thread-1]: coreCloseExecutor-8883-thread-1 finishFullFlush success=true [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: startCommit(): start [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: skip startCommit(): no changes pending [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: commit: pendingCommit == null; skip [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: commit: took 0.3 msec [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: commit: done [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: rollback [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: all running merges have aborted [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: rollback: done finish merges [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-8883-thread-1]: abort [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-8883-thread-1]: done abort success=true [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-8883-thread-1]: rollback: infos= [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-8883-thread-1]: now checkpoint "" [0 segments ; isCommit = false] [junit4] 2> 2224045 INFO (coreCloseExecutor-8883-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-8883-thread-1]: 0 msec to checkpoint [junit4] 2> 2224046 INFO (SUITE-TestSolrIndexConfig-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=912, maxMBSortInHeap=5.370552358816802, sim=RandomSimilarity(queryNorm=false): {}, locale=en-SG, timezone=Asia/Anadyr [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=300274320,total=509038592 [junit4] 2> NOTE: All tests run in this JVM: [TestShortCircuitedRequests, CSVRequestHandlerTest, TestMacroExpander, TestFunctionQuery, AlternateDirectoryTest, TestNonDefinedSimilarityFactory, CoreAdminRequestStatusTest, GraphQueryTest, RegexBoostProcessorTest, ActionThrottleTest, TestSQLHandler, PKIAuthenticationIntegrationTest, BJQParserTest, HdfsNNFailoverTest, TestPHPSerializedResponseWriter, TestImpersonationWithHadoopAuth, DistributedFacetPivotSmallTest, TestFieldCacheVsDocValues, TestNumericRangeQuery64, LeaderInitiatedRecoveryOnCommitTest, InfixSuggestersTest, LeaderFailoverAfterPartitionTest, HighlighterConfigTest, TestPushWriter, HighlighterTest, TestTrackingShardHandlerFactory, SpatialFilterTest, TestExportWriter, TestComplexPhraseLeadingWildcard, TestSerializedLuceneMatchVersion, TestLockTree, ClusterStateUpdateTest, TestClassicSimilarityFactory, DistributedQueryComponentCustomSortTest, CollectionsAPISolrJTest, SortByFunctionTest, ReplicationFactorTest, ZkCLITest, LeaderElectionTest, DistanceFunctionTest, JSONWriterTest, TestSchemaSimilarityResource, DistributedFacetExistsSmallTest, TestFreeTextSuggestions, TestAddFieldRealTimeGet, TestRandomFlRTGCloud, HdfsChaosMonkeySafeLeaderTest, NumericFieldsTest, ExitableDirectoryReaderTest, TestMissingGroups, ClassificationUpdateProcessorTest, TestLegacyNumericRangeQueryBuilder, PrimUtilsTest, SolrJmxReporterTest, ZkControllerTest, FieldAnalysisRequestHandlerTest, TestSearcherReuse, TestDistributedSearch, MetricsConfigTest, SimpleFacetsTest, TestSolrQueryResponse, ForceLeaderTest, TestQueryWrapperFilter, QueryElevationComponentTest, TestRecoveryHdfs, ExplicitHLLTest, TestReplicationHandler, TestRTimerTree, TestAtomicUpdateErrorCases, DirectUpdateHandlerOptimizeTest, DocExpirationUpdateProcessorFactoryTest, RequestLoggingTest, TestStressReorder, TestFieldTypeCollectionResource, TestClusterProperties, TestFuzzyAnalyzedSuggestions, RequiredFieldsTest, TestAuthenticationFramework, URLClassifyProcessorTest, OutputWriterTest, PathHierarchyTokenizerFactoryTest, SolrInfoBeanTest, TestSha256AuthenticationProvider, TestRawTransformer, TestGroupingSearch, TestStressVersions, TestShardHandlerFactory, TestMultiValuedNumericRangeQuery, ResponseLogComponentTest, CdcrBootstrapTest, TestIBSimilarityFactory, TestNestedDocsSort, TestPKIAuthenticationPlugin, TimeZoneUtilsTest, DistributedDebugComponentTest, AnalysisAfterCoreReloadTest, TestTolerantUpdateProcessorRandomCloud, CurrencyFieldTypeTest, HdfsWriteToMultipleCollectionsTest, PrimitiveFieldTypeTest, ShardRoutingTest, UniqFieldsUpdateProcessorFactoryTest, DeleteShardTest, HardAutoCommitTest, TestOrdValues, PeerSyncWithIndexFingerprintCachingTest, TestTlogReplica, TestStressInPlaceUpdates, TestRebalanceLeaders, TestLeaderElectionWithEmptyReplica, TestOnReconnectListenerSupport, PeerSyncReplicationTest, TestFilteredDocIdSet, TestSolrConfigHandlerCloud, RequestHandlersTest, TestMaxTokenLenTokenizer, InfoHandlerTest, HighlighterMaxOffsetTest, TestSlowCompositeReaderWrapper, TestZkAclsWithHadoopAuth, TestDFRSimilarityFactory, SolrIndexSplitterTest, SubstringBytesRefFilterTest, RAMDirectoryFactoryTest, TestConfigOverlay, HdfsBasicDistributedZkTest, TestCSVResponseWriter, DirectUpdateHandlerTest, TestSweetSpotSimilarityFactory, TestSolrCloudWithSecureImpersonation, QueryParsingTest, TestQuerySenderListener, DocValuesMissingTest, TestSolrCloudWithDelegationTokens, TestApiFramework, TestGraphMLResponseWriter, TestSystemCollAutoCreate, HdfsTlogReplayBufferedWhileIndexingTest, BasicAuthIntegrationTest, TestCursorMarkWithoutUniqueKey, TestBlendedInfixSuggestions, TestLeaderInitiatedRecoveryThread, TestInfoStreamLogging, TestExactStatsCache, CoreSorterTest, TestHdfsBackupRestoreCore, SliceStateTest, TestPullReplica, ReturnFieldsTest, TestSubQueryTransformerDistrib, TestCodecSupport, TestBlobHandler, TestPointFields, TestManagedSchemaAPI, TestSolrDeletionPolicy2, TestHashPartitioner, TestJsonFacets, IndexBasedSpellCheckerTest, ResponseHeaderTest, TestNumericTerms64, TestLegacyNumericUtils, TestHdfsUpdateLog, TestFieldCollectionResource, MoveReplicaTest, TestRandomDVFaceting, DistributedVersionInfoTest, FastVectorHighlighterTest, TestJmxIntegration, TriLevelCompositeIdRoutingTest, TestSubQueryTransformer, CdcrVersionReplicationTest, NodeMutatorTest, ClassificationUpdateProcessorFactoryTest, OverseerModifyCollectionTest, TestLegacyFieldCache, ChaosMonkeySafeLeaderWithPullReplicasTest, SparseHLLTest, ShufflingReplicaListTransformerTest, AnalyticsQueryTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, OverseerTest, RecoveryZkTest, TestRandomFaceting, TestDistributedGrouping, TestFaceting, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestJoin, TestCoreContainer, TestSolr4Spatial, SolrCoreTest, SpellCheckComponentTest, SolrCmdDistributorTest, PeerSyncTest, TestSort, TestFiltering, BasicFunctionalityTest, TestLazyCores, TestBadConfig, SoftAutoCommitTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, TestExtendedDismaxParser, CoreAdminHandlerTest, SuggesterTest, TestStressLucene, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, TestPseudoReturnFields, TestUpdate, FieldMutatingUpdateProcessorTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, XsltUpdateRequestHandlerTest, CacheHeaderTest, DisMaxRequestHandlerTest, TestWriterPerf, DirectSolrSpellCheckerTest, TestQueryTypes, XmlUpdateRequestHandlerTest, TestValueSourceCache, TestIndexingPerformance, TestSolrQueryParser, TestCollationField, PingRequestHandlerTest, UpdateParamsTest, TestSolrIndexConfig] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrIndexConfig -Dtests.seed=E17FA859CCCA9C52 -Dtests.slow=true -Dtests.locale=en-SG -Dtests.timezone=Asia/Anadyr -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J0 | TestSolrIndexConfig (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-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrIndexConfig_E17FA859CCCA9C52-001\init-core-data-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestSolrIndexConfig_E17FA859CCCA9C52-001\init-core-data-001 [junit4] > at __randomizedtesting.SeedInfo.seed([E17FA859CCCA9C52]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [441/733 (1!)] on J0 in 0.45s, 2 tests, 1 error <<< FAILURES! [...truncated 188 lines...] [junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest [junit4] 2> 2235179 INFO (SUITE-ChaosMonkeyNothingIsSafeTest-seed#[E17FA859CCCA9C52]-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-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeTest_E17FA859CCCA9C52-001\init-core-data-001 [junit4] 2> 2235180 WARN (SUITE-ChaosMonkeyNothingIsSafeTest-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 2235181 INFO (SUITE-ChaosMonkeyNothingIsSafeTest-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 2235187 INFO (SUITE-ChaosMonkeyNothingIsSafeTest-seed#[E17FA859CCCA9C52]-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> 2235187 INFO (SUITE-ChaosMonkeyNothingIsSafeTest-seed#[E17FA859CCCA9C52]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /nm/p [junit4] 2> 2235190 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2235191 INFO (Thread-3433) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2235191 INFO (Thread-3433) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2235195 ERROR (Thread-3433) [ ] 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> 2235290 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.ZkTestServer start zk server on port:54975 [junit4] 2> 2235311 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 2235313 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\schema15.xml to /configs/conf1/schema.xml [junit4] 2> 2235315 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2235316 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 2235319 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 2235320 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4] 2> 2235323 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 2235324 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 2235325 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2235326 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 2235329 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractZkTestCase put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 2235331 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use TLOG replicas unless explicitly asked otherwise [junit4] 2> 2235791 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 2235791 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@741bc2eb{/nm/p,null,AVAILABLE} [junit4] 2> 2235796 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7558393{HTTP/1.1,[http/1.1]}{127.0.0.1:54982} [junit4] 2> 2235796 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.e.j.s.Server Started @2243874ms [junit4] 2> 2235796 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeTest_E17FA859CCCA9C52-001\tempDir-001/control/data, replicaType=NRT, hostContext=/nm/p, hostPort=54982, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeTest_E17FA859CCCA9C52-001\control-001\cores} [junit4] 2> 2235796 ERROR (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2235796 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2235796 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2235796 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 2235796 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-28T07:07:03.518Z [junit4] 2> 2235800 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2235800 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeTest_E17FA859CCCA9C52-001\control-001\solr.xml [junit4] 2> 2235805 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@64346e5f, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 2235810 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54975/solr [junit4] 2> 2235891 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [n:127.0.0.1:54982_nm%2Fp ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2235891 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [n:127.0.0.1:54982_nm%2Fp ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54982_nm%2Fp [junit4] 2> 2235892 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [n:127.0.0.1:54982_nm%2Fp ] o.a.s.c.Overseer Overseer (id=98735385668222980-127.0.0.1:54982_nm%2Fp-n_0000000000) starting [junit4] 2> 2235898 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [n:127.0.0.1:54982_nm%2Fp ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54982_nm%2Fp [junit4] 2> 2235900 INFO (zkCallback-2412-thread-1-processing-n:127.0.0.1:54982_nm%2Fp) [n:127.0.0.1:54982_nm%2Fp ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2235992 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [n:127.0.0.1:54982_nm%2Fp ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@64346e5f [junit4] 2> 2235998 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [n:127.0.0.1:54982_nm%2Fp ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@64346e5f [junit4] 2> 2235998 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [n:127.0.0.1:54982_nm%2Fp ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@64346e5f [junit4] 2> 2236002 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [n:127.0.0.1:54982_nm%2Fp ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.ChaosMonkeyNothingIsSafeTest_E17FA859CCCA9C52-001\control-001\cores [junit4] 2> 2236042 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2236043 INFO (TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54975/solr ready [junit4] 2> 2236050 INFO (qtp1027752173-18882) [n:127.0.0.1:54982_nm%2Fp ] 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:54982_nm%252Fp&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2236053 INFO (OverseerThreadFactory-7906-thread-1-processing-n:127.0.0.1:54982_nm%2Fp) [n:127.0.0.1:54982_nm%2Fp ] o.a.s.c.CreateCollectionCmd Create collection control_collection [junit4] 2> 2236186 INFO (qtp1027752173-18883) [n:127.0.0.1:54982_nm%2Fp ] 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> 2236187 INFO (qtp1027752173-18883) [n:127.0.0.1:54982_nm%2Fp ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 2236295 INFO (zkCallback-2412-thread-1-processing-n:127.0.0.1:54982_nm%2Fp) [n:127.0.0.1:54982_nm%2Fp ] 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> 2237209 INFO (qtp1027752173-18883) [n:127.0.0.1:54982_nm%2Fp c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2237217 INFO (qtp1027752173-18883) [n:127.0.0.1:54982_nm%2Fp 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> 2237284 INFO (qtp1027752173-18883) [n:127.0.0.1:54982_nm%2Fp 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> 2237300 INFO (qtp1027752173-18883) [n:127.0.0.1:54982_nm%2Fp c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_ [...truncated too long message...] kSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > 8) Thread[id=18918, name=TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]-EventThread, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) [junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) [junit4] > at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:501) [junit4] > 9) Thread[id=18917, name=TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]-SendThread(127.0.0.1:54975), state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] [junit4] > at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method) [junit4] > at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:296) [junit4] > at sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:278) [junit4] > at sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:159) [junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) [junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) [junit4] > at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349) [junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141) [junit4] > at __randomizedtesting.SeedInfo.seed([E17FA859CCCA9C52]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=19057, name=zkCallback-2419-thread-5, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > 2) Thread[id=19055, name=zkCallback-2419-thread-3, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > 3) Thread[id=19080, name=zkCallback-2419-thread-6, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > 4) Thread[id=19054, name=zkCallback-2419-thread-2, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > 5) Thread[id=18919, name=zkCallback-2419-thread-1, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > 6) Thread[id=19056, name=zkCallback-2419-thread-4, state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) [junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > 7) Thread[id=18917, name=TEST-ChaosMonkeyNothingIsSafeTest.test-seed#[E17FA859CCCA9C52]-SendThread(127.0.0.1:54975), state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] [junit4] > at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll0(Native Method) [junit4] > at sun.nio.ch.WindowsSelectorImpl$SubSelector.poll(WindowsSelectorImpl.java:296) [junit4] > at sun.nio.ch.WindowsSelectorImpl$SubSelector.access$400(WindowsSelectorImpl.java:278) [junit4] > at sun.nio.ch.WindowsSelectorImpl.doSelect(WindowsSelectorImpl.java:159) [junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) [junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) [junit4] > at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:349) [junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1141) [junit4] > at __randomizedtesting.SeedInfo.seed([E17FA859CCCA9C52]:0) [junit4] Completed [504/733 (2!)] on J1 in 71.48s, 1 test, 3 errors <<< FAILURES! [...truncated 47379 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
