Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/442/ Java: 32bit/jdk1.8.0_144 -client -XX:+UseG1GC
5 tests failed. FAILED: junit.framework.TestSuite.org.apache.lucene.index.TestBackwardsCompatibility Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_55A698D0B2D91473-001\2.9.3-cfs-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_55A698D0B2D91473-001\2.9.3-cfs-001 Stack Trace: java.io.IOException: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_55A698D0B2D91473-001\2.9.3-cfs-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_55A698D0B2D91473-001\2.9.3-cfs-001 at __randomizedtesting.SeedInfo.seed([55A698D0B2D91473]:0) at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216) at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestCloudInspectUtil Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudInspectUtil_742DD335A645922B-001\init-core-data-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudInspectUtil_742DD335A645922B-001\init-core-data-001 Stack Trace: java.io.IOException: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudInspectUtil_742DD335A645922B-001\init-core-data-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudInspectUtil_742DD335A645922B-001\init-core-data-001 at __randomizedtesting.SeedInfo.seed([742DD335A645922B]:0) at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216) at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.TriggerIntegrationTest Error Message: 1 thread leaked from SUITE scope at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest: 1) Thread[id=12865, name=qtp15834114-12865, state=TIMED_WAITING, group=TGRP-TriggerIntegrationTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest: 1) Thread[id=12865, name=qtp15834114-12865, state=TIMED_WAITING, group=TGRP-TriggerIntegrationTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([742DD335A645922B]:0) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.TriggerIntegrationTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=12865, name=qtp15834114-12865, state=TIMED_WAITING, group=TGRP-TriggerIntegrationTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: 1) Thread[id=12865, name=qtp15834114-12865, state=TIMED_WAITING, group=TGRP-TriggerIntegrationTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([742DD335A645922B]:0) FAILED: junit.framework.TestSuite.org.apache.solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-sent.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-sent.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-tokenizer.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-tokenizer.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-ner-person.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-ner-person.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001 Stack Trace: java.io.IOException: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-sent.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-sent.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-tokenizer.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-tokenizer.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-ner-person.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-ner-person.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001 at __randomizedtesting.SeedInfo.seed([584A1A145388B31B]: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 4737 lines...] [junit4] Suite: org.apache.lucene.index.TestBackwardsCompatibility [junit4] IGNOR/A 0.02s J1 | TestBackwardsCompatibility.testCreateSingleSegmentCFS [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateNoCFS [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateEmptyIndex [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateCFS [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateSingleSegmentNoCFS [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateSortedIndex [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateIndexWithDocValuesUpdates [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] IGNOR/A 0.00s J1 | TestBackwardsCompatibility.testCreateMoreTermsIndex [junit4] > Assumption #1: backcompat creation tests must be run with -Dtests.bwcdir=/path/to/write/indexes [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=true): {content6=DFR I(ne)LZ(0.3), content5=IB SPL-L2, content2=DFR GB2, utf8=IB LL-D3(800.0), fie?ld=DFR I(ne)3(800.0), content=DFR GB3(800.0), autf8=DFR I(ne)B2}, locale=lv, timezone=Asia/Seoul [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=102114168,total=146800640 [junit4] 2> NOTE: All tests run in this JVM: [TestIndexWriterOnOldIndex, TestLucene62SegmentInfoFormat, TestBackwardsCompatibility] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBackwardsCompatibility -Dtests.seed=55A698D0B2D91473 -Dtests.slow=true -Dtests.locale=lv -Dtests.timezone=Asia/Seoul -Dtests.asserts=true -Dtests.file.encoding=Cp1252 [junit4] ERROR 0.00s J1 | TestBackwardsCompatibility (suite) <<< [junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts): [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_55A698D0B2D91473-001\2.9.3-cfs-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\backward-codecs\test\J1\temp\lucene.index.TestBackwardsCompatibility_55A698D0B2D91473-001\2.9.3-cfs-001 [junit4] > at __randomizedtesting.SeedInfo.seed([55A698D0B2D91473]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [5/8 (1!)] on J1 in 45.61s, 30 tests, 1 error, 8 skipped <<< FAILURES! [...truncated 7169 lines...] [junit4] Suite: org.apache.solr.cloud.TestCloudInspectUtil [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudInspectUtil_742DD335A645922B-001\init-core-data-001 [junit4] 2> 599589 WARN (SUITE-TestCloudInspectUtil-seed#[742DD335A645922B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 599589 INFO (SUITE-TestCloudInspectUtil-seed#[742DD335A645922B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 599597 INFO (SUITE-TestCloudInspectUtil-seed#[742DD335A645922B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 599597 INFO (SUITE-TestCloudInspectUtil-seed#[742DD335A645922B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 599599 INFO (TEST-TestCloudInspectUtil.testCheckIfDiffIsLegal-seed#[742DD335A645922B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCheckIfDiffIsLegal [junit4] 2> ###### Only in control: [{id=3}, {id=2}] [junit4] 2> ###### Only in cloud: [{id=1}] [junit4] 2> ###### Only in control: [{id=4}] [junit4] 2> ###### Only in cloud: [] [junit4] 2> ###### Only in control: 4, but this is expected because we found an add fail for 4 [junit4] 2> ###### Only in control: [{id=4}] [junit4] 2> ###### Only in cloud: [{id=5}] [junit4] 2> ###### Only in control: 4, but this is expected because we found an add fail for 4 [junit4] 2> ###### Only in cloud: 5, but this is expected because we found a delete fail for 5 [junit4] 2> ###### Only in control: [{id=4}] [junit4] 2> ###### Only in cloud: [{id=5}] [junit4] 2> ###### Only in control: 4, but this is expected because we found an add fail for 4 [junit4] 2> 599601 INFO (TEST-TestCloudInspectUtil.testCheckIfDiffIsLegal-seed#[742DD335A645922B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCheckIfDiffIsLegal [junit4] 2> 599602 INFO (SUITE-TestCloudInspectUtil-seed#[742DD335A645922B]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=RandomSimilarity(queryNorm=true): {}, locale=es-CU, timezone=Pacific/Guam [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=231245680,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [TestStressLucene, SolrMetricManagerTest, TestConfigSetsAPIExclusivity, AssignTest, CoreMergeIndexesAdminHandlerTest, UUIDUpdateProcessorFallbackTest, ResponseLogComponentTest, CdcrReplicationDistributedZkTest, MoveReplicaHDFSFailoverTest, URLClassifyProcessorTest, BitVectorTest, TestSolrCLIRunExample, TestStressRecovery, TestCoreAdminApis, AtomicUpdatesTest, TestPullReplica, TestDistributedSearch, RestartWhileUpdatingTest, TestLRUStatsCache, TestSolr4Spatial, HdfsUnloadDistributedZkTest, TestBulkSchemaConcurrent, TestLMDirichletSimilarityFactory, TestOnReconnectListenerSupport, TestSkipOverseerOperations, TestCloudSchemaless, TestSimpleTextCodec, OverseerModifyCollectionTest, TestGeoJSONResponseWriter, ExecutePlanActionTest, TestAnalyzeInfixSuggestions, TestCloudInspectUtil] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCloudInspectUtil -Dtests.seed=742DD335A645922B -Dtests.slow=true -Dtests.locale=es-CU -Dtests.timezone=Pacific/Guam -Dtests.asserts=true -Dtests.file.encoding=Cp1252 [junit4] ERROR 0.00s J1 | TestCloudInspectUtil (suite) <<< [junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts): [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudInspectUtil_742DD335A645922B-001\init-core-data-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudInspectUtil_742DD335A645922B-001\init-core-data-001 [junit4] > at __randomizedtesting.SeedInfo.seed([742DD335A645922B]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [60/774 (1!)] on J1 in 0.05s, 1 test, 1 error <<< FAILURES! [...truncated 624 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_742DD335A645922B-001\init-core-data-001 [junit4] 2> 1474150 WARN (SUITE-TriggerIntegrationTest-seed#[742DD335A645922B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9 [junit4] 2> 1474150 INFO (SUITE-TriggerIntegrationTest-seed#[742DD335A645922B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 1474156 INFO (SUITE-TriggerIntegrationTest-seed#[742DD335A645922B]-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> 1474156 INFO (SUITE-TriggerIntegrationTest-seed#[742DD335A645922B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1474157 INFO (SUITE-TriggerIntegrationTest-seed#[742DD335A645922B]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_742DD335A645922B-001\tempDir-001 [junit4] 2> 1474157 INFO (SUITE-TriggerIntegrationTest-seed#[742DD335A645922B]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1474158 INFO (Thread-2753) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1474158 INFO (Thread-2753) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1474163 ERROR (Thread-2753) [ ] 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> 1474258 INFO (SUITE-TriggerIntegrationTest-seed#[742DD335A645922B]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:54074 [junit4] 2> 1474270 INFO (zkConnectionManagerCallback-2490-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474295 INFO (jetty-launcher-2487-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1474297 INFO (jetty-launcher-2487-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1474298 INFO (jetty-launcher-2487-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1474298 INFO (jetty-launcher-2487-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1474298 INFO (jetty-launcher-2487-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 1474298 INFO (jetty-launcher-2487-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b1d2a0{/solr,null,AVAILABLE} [junit4] 2> 1474300 INFO (jetty-launcher-2487-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1e6818{HTTP/1.1,[http/1.1]}{127.0.0.1:54081} [junit4] 2> 1474300 INFO (jetty-launcher-2487-thread-2) [ ] o.e.j.s.Server Started @1482338ms [junit4] 2> 1474300 INFO (jetty-launcher-2487-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54081} [junit4] 2> 1474300 ERROR (jetty-launcher-2487-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1474300 INFO (jetty-launcher-2487-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 1474300 INFO (jetty-launcher-2487-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1474300 INFO (jetty-launcher-2487-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1474300 INFO (jetty-launcher-2487-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-08T16:10:48.415Z [junit4] 2> 1474303 INFO (jetty-launcher-2487-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1474303 INFO (jetty-launcher-2487-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1474304 INFO (jetty-launcher-2487-thread-1) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1474304 INFO (jetty-launcher-2487-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1ac5dec{/solr,null,AVAILABLE} [junit4] 2> 1474306 INFO (jetty-launcher-2487-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@938c74{HTTP/1.1,[http/1.1]}{127.0.0.1:54087} [junit4] 2> 1474306 INFO (jetty-launcher-2487-thread-1) [ ] o.e.j.s.Server Started @1482344ms [junit4] 2> 1474306 INFO (jetty-launcher-2487-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54087} [junit4] 2> 1474306 ERROR (jetty-launcher-2487-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1474306 INFO (jetty-launcher-2487-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 1474306 INFO (jetty-launcher-2487-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1474306 INFO (jetty-launcher-2487-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1474306 INFO (jetty-launcher-2487-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-08T16:10:48.421Z [junit4] 2> 1474310 INFO (zkConnectionManagerCallback-2492-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474310 INFO (jetty-launcher-2487-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1474310 INFO (zkConnectionManagerCallback-2494-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474311 INFO (jetty-launcher-2487-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1474325 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100603b09320001, likely client has closed socket [junit4] 2> 1474331 INFO (jetty-launcher-2487-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54074/solr [junit4] 2> 1474331 INFO (jetty-launcher-2487-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54074/solr [junit4] 2> 1474334 INFO (zkConnectionManagerCallback-2500-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474335 INFO (zkConnectionManagerCallback-2502-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474336 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100603b09320004, likely client has closed socket [junit4] 2> 1474339 INFO (zkConnectionManagerCallback-2504-thread-1-processing-n:127.0.0.1:54087_solr) [n:127.0.0.1:54087_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474339 INFO (zkConnectionManagerCallback-2506-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474482 INFO (jetty-launcher-2487-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1474482 INFO (jetty-launcher-2487-thread-1) [n:127.0.0.1:54087_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1474483 INFO (jetty-launcher-2487-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54081_solr [junit4] 2> 1474484 INFO (jetty-launcher-2487-thread-1) [n:127.0.0.1:54087_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54087_solr [junit4] 2> 1474485 INFO (jetty-launcher-2487-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.Overseer Overseer (id=72163400711536646-127.0.0.1:54081_solr-n_0000000000) starting [junit4] 2> 1474494 INFO (jetty-launcher-2487-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54081_solr [junit4] 2> 1474499 INFO (zkCallback-2503-thread-1-processing-n:127.0.0.1:54087_solr) [n:127.0.0.1:54087_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1474500 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 1474506 INFO (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1474508 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 1474509 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54087_solr] [junit4] 2> 1474515 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 1474515 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 1474519 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1474520 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 1474520 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 [junit4] 2> 1474756 INFO (jetty-launcher-2487-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54081.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1474768 INFO (jetty-launcher-2487-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54081.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1474768 INFO (jetty-launcher-2487-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54081.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1474770 INFO (jetty-launcher-2487-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_742DD335A645922B-001\tempDir-001\node2\. [junit4] 2> 1474796 INFO (jetty-launcher-2487-thread-1) [n:127.0.0.1:54087_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54087.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1474807 INFO (jetty-launcher-2487-thread-1) [n:127.0.0.1:54087_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54087.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1474808 INFO (jetty-launcher-2487-thread-1) [n:127.0.0.1:54087_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54087.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1474810 INFO (jetty-launcher-2487-thread-1) [n:127.0.0.1:54087_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_742DD335A645922B-001\tempDir-001\node1\. [junit4] 2> 1474845 INFO (zkConnectionManagerCallback-2512-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474851 INFO (zkConnectionManagerCallback-2516-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474854 INFO (SUITE-TriggerIntegrationTest-seed#[742DD335A645922B]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1474856 INFO (SUITE-TriggerIntegrationTest-seed#[742DD335A645922B]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54074/solr ready [junit4] 2> 1474882 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTriggerThrottling [junit4] 2> 1474883 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2 [junit4] 2> 1474884 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2 [junit4] 2> 1474884 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2 [junit4] 2> 1474885 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 1474887 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1474887 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 [junit4] 2> 1474899 INFO (qtp33528256-12366) [n:127.0.0.1:54081_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1474903 INFO (qtp13875718-12380) [n:127.0.0.1:54087_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1474903 DEBUG (qtp13875718-12364) [n:127.0.0.1:54087_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1474904 INFO (qtp13875718-12364) [n:127.0.0.1:54087_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=11 [junit4] 2> 1474906 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3 [junit4] 2> 1474907 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54087_solr] [junit4] 2> 1474907 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 1474907 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3 [junit4] 2> 1474909 INFO (qtp33528256-12369) [n:127.0.0.1:54081_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1474910 INFO (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 1474911 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 1474912 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3 [junit4] 2> 1474912 INFO (qtp13875718-12380) [n:127.0.0.1:54087_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1474912 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 1474912 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 1474912 DEBUG (qtp13875718-12378) [n:127.0.0.1:54087_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1474913 INFO (qtp13875718-12378) [n:127.0.0.1:54087_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=6 [junit4] 2> 1474913 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4 [junit4] 2> 1474914 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54087_solr] [junit4] 2> 1474914 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 1474915 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54087_solr] [junit4] 2> 1474915 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 1474915 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4 [junit4] 2> 1474917 INFO (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 1474918 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 1474918 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4 [junit4] 2> 1474919 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1474919 DEBUG (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 1474919 DEBUG (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 1474919 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1474919 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1474919 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1474919 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1598adb{/solr,null,AVAILABLE} [junit4] 2> 1474920 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1941863{HTTP/1.1,[http/1.1]}{127.0.0.1:54115} [junit4] 2> 1474920 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.e.j.s.Server Started @1482958ms [junit4] 2> 1474920 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54115} [junit4] 2> 1474920 ERROR (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1474920 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 1474921 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1474921 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1474921 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-08T16:10:49.036Z [junit4] 2> 1474924 INFO (zkConnectionManagerCallback-2518-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474925 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1474939 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54074/solr [junit4] 2> 1474943 INFO (zkConnectionManagerCallback-2522-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474946 INFO (zkConnectionManagerCallback-2524-thread-1-processing-n:127.0.0.1:54115_solr) [n:127.0.0.1:54115_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1474957 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [n:127.0.0.1:54115_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1474961 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [n:127.0.0.1:54115_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1474964 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [n:127.0.0.1:54115_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54115_solr [junit4] 2> 1474966 INFO (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1474966 INFO (zkCallback-2503-thread-1-processing-n:127.0.0.1:54087_solr) [n:127.0.0.1:54087_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1474968 INFO (zkCallback-2523-thread-1-processing-n:127.0.0.1:54115_solr) [n:127.0.0.1:54115_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1474968 INFO (zkCallback-2515-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1475140 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [n:127.0.0.1:54115_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54115.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1475151 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [n:127.0.0.1:54115_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54115.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1475152 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [n:127.0.0.1:54115_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54115.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1475153 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [n:127.0.0.1:54115_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_742DD335A645922B-001\tempDir-001\node3\. [junit4] 2> 1475914 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 1475914 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1475914 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:54115_solr at time 1614483467252258 [junit4] 2> 1475914 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing registered processor for nodes: [127.0.0.1:54115_solr] added at times [1614483467252258], now=1614483467296062 [junit4] 2> 1475915 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"5bc5d398fb222T6aqer1cm8pq30ridab7c7pynl", [junit4] 2> "source":"node_added_trigger1", [junit4] 2> "eventTime":1614483467252258, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1614483467252258], [junit4] 2> "nodeNames":["127.0.0.1:54115_solr"]}} [junit4] 2> 1475915 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_trigger2, node_added_trigger1] [junit4] 2> 1475916 INFO (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 1475916 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"5bc5d398fb222T6aqer1cm8pq30ridab7c7pynl", [junit4] 2> "source":"node_added_trigger1", [junit4] 2> "eventTime":1614483467252258, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1614483467252258], [junit4] 2> "_enqueue_time_":1614483468498970, [junit4] 2> "nodeNames":["127.0.0.1:54115_solr"]}} [junit4] 2> 1475916 INFO (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest action executed from node_added_trigger1 [junit4] 2> 1475920 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 5000ms [junit4] 2> 1475920 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 5000ms [junit4] 2> 1475920 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 2 ms for event id=5bc5d398fb222T6aqer1cm8pq30ridab7c7pynl [junit4] 2> 1480921 DEBUG (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 1480921 DEBUG (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 1480921 DEBUG (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1480921 DEBUG (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 1480921 DEBUG (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:54115_solr at time 1614488474714327 [junit4] 2> 1480921 DEBUG (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 firing registered processor for nodes: [127.0.0.1:54115_solr] added at times [1614488474714327], now=1614488474838913 [junit4] 2> 1480922 DEBUG (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"5bc5e640780d7T6aqer1cm8pq30ridab7c7pynn", [junit4] 2> "source":"node_added_trigger2", [junit4] 2> "eventTime":1614488474714327, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1614488474714327], [junit4] 2> "nodeNames":["127.0.0.1:54115_solr"]}} [junit4] 2> 1480922 DEBUG (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_trigger2, node_added_trigger1] [junit4] 2> 1480923 INFO (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 1480923 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"5bc5e640780d7T6aqer1cm8pq30ridab7c7pynn", [junit4] 2> "source":"node_added_trigger2", [junit4] 2> "eventTime":1614488474714327, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1614488474714327], [junit4] 2> "_enqueue_time_":1614488475159764, [junit4] 2> "nodeNames":["127.0.0.1:54115_solr"]}} [junit4] 2> 1480924 INFO (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest last action at 1518106250031000000 current time = 1518106255038000000 [junit4] 2> real diff: 5007000000 [junit4] 2> min diff: 4998000000 [junit4] 2> 1480924 INFO (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest action executed from node_added_trigger2 [junit4] 2> 1480925 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 5000ms [junit4] 2> 1480926 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 5000ms [junit4] 2> 1480926 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 2 ms for event id=5bc5e640780d7T6aqer1cm8pq30ridab7c7pynn [junit4] 2> 1480933 INFO (qtp33528256-12369) [n:127.0.0.1:54081_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1480937 INFO (qtp13875718-12378) [n:127.0.0.1:54087_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1480941 INFO (qtp15541846-12438) [n:127.0.0.1:54115_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1480941 DEBUG (qtp15541846-12434) [n:127.0.0.1:54115_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1480942 INFO (qtp15541846-12434) [n:127.0.0.1:54115_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=15 [junit4] 2> 1480943 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5 [junit4] 2> 1480943 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54115_solr, 127.0.0.1:54087_solr] [junit4] 2> 1480943 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 1480943 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54115_solr, 127.0.0.1:54087_solr] [junit4] 2> 1480943 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54115_solr, 127.0.0.1:54087_solr] [junit4] 2> 1480943 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 1480943 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5 [junit4] 2> 1480949 INFO (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 1480950 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5 [junit4] 2> 1480951 DEBUG (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with currently live nodes: 3 [junit4] 2> 1480952 INFO (qtp33528256-12372) [n:127.0.0.1:54081_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1480956 INFO (qtp13875718-12378) [n:127.0.0.1:54087_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1480959 INFO (qtp15541846-12438) [n:127.0.0.1:54115_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1480960 DEBUG (qtp13875718-12381) [n:127.0.0.1:54087_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1480961 INFO (qtp13875718-12381) [n:127.0.0.1:54087_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=13 [junit4] 2> 1480962 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6 [junit4] 2> 1480962 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54115_solr, 127.0.0.1:54087_solr] [junit4] 2> 1480962 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 1480963 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54115_solr, 127.0.0.1:54087_solr] [junit4] 2> 1480964 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54115_solr, 127.0.0.1:54087_solr] [junit4] 2> 1480964 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true} [junit4] 2> 1480964 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54115_solr, 127.0.0.1:54087_solr] [junit4] 2> 1480964 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6 [junit4] 2> 1480969 INFO (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 1480969 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6 [junit4] 2> 1480970 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1941863{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 1480970 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=15696400 [junit4] 2> 1480970 DEBUG (ScheduledTrigger-5352-thread-3) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with currently live nodes: 3 [junit4] 2> 1480970 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1480971 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e636c6: rootName = solr_54115, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@48510 [junit4] 2> 1480978 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1480978 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@19045f8: rootName = solr_54115, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1d66c6 [junit4] 2> 1480985 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1480985 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1591a3a: rootName = solr_54115, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@12e77d2 [junit4] 2> 1480988 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:54115_solr [junit4] 2> 1480989 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1480990 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1480990 INFO (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1480991 INFO (zkCallback-2523-thread-1-processing-n:127.0.0.1:54115_solr) [n:127.0.0.1:54115_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1480991 INFO (zkCallback-2515-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1480991 INFO (zkCallback-2503-thread-1-processing-n:127.0.0.1:54087_solr) [n:127.0.0.1:54087_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1480991 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1598adb{/solr,null,UNAVAILABLE} [junit4] 2> 1480991 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 1481952 DEBUG (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with currently live nodes: 2 [junit4] 2> 1481952 DEBUG (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:54115_solr [junit4] 2> 1481952 DEBUG (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:54115_solr] [junit4] 2> 1481952 DEBUG (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"5bc5ea172e735T6aqer1cm8pq30ridab7c7pynp", [junit4] 2> "source":"node_lost_trigger1", [junit4] 2> "eventTime":1614489505163061, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1614489505163061], [junit4] 2> "nodeNames":["127.0.0.1:54115_solr"]}} [junit4] 2> 1481952 DEBUG (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_lost_trigger2, node_added_trigger2, node_lost_trigger1, node_added_trigger1] [junit4] 2> 1481953 INFO (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 1481953 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"5bc5ea172e735T6aqer1cm8pq30ridab7c7pynp", [junit4] 2> "source":"node_lost_trigger1", [junit4] 2> "eventTime":1614489505163061, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1614489505163061], [junit4] 2> "_enqueue_time_":1614489505392890, [junit4] 2> "nodeNames":["127.0.0.1:54115_solr"]}} [junit4] 2> 1481953 INFO (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest action executed from node_lost_trigger1 [junit4] 2> 1481955 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger2 after 5000ms [junit4] 2> 1481955 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 5000ms [junit4] 2> 1481955 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger1 after 5000ms [junit4] 2> 1481955 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 5000ms [junit4] 2> 1481955 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 2 ms for event id=5bc5ea172e735T6aqer1cm8pq30ridab7c7pynp [junit4] 2> 1486958 DEBUG (ScheduledTrigger-5352-thread-3) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2 [junit4] 2> 1486958 DEBUG (ScheduledTrigger-5352-thread-2) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with currently live nodes: 2 [junit4] 2> 1486958 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1 [junit4] 2> 1486958 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 1486958 DEBUG (ScheduledTrigger-5352-thread-3) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 1486958 DEBUG (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger2 with currently live nodes: 2 [junit4] 2> 1486959 DEBUG (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 127.0.0.1:54115_solr [junit4] 2> 1486959 DEBUG (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing registered processor for lost nodes: [127.0.0.1:54115_solr] [junit4] 2> 1486959 DEBUG (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"5bc5fcbe30509T6aqer1cm8pq30ridab7c7pynr", [junit4] 2> "source":"node_lost_trigger2", [junit4] 2> "eventTime":1614494512121097, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1614494512121097], [junit4] 2> "nodeNames":["127.0.0.1:54115_solr"]}} [junit4] 2> 1486959 DEBUG (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_lost_trigger2, node_added_trigger2, node_lost_trigger1, node_added_trigger1] [junit4] 2> 1486960 INFO (ScheduledTrigger-5352-thread-4) [n:127.0.0.1:54081_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 1486960 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"5bc5fcbe30509T6aqer1cm8pq30ridab7c7pynr", [junit4] 2> "source":"node_lost_trigger2", [junit4] 2> "eventTime":1614494512121097, [junit4] 2> "eventType":"NODELOST", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[1614494512121097], [junit4] 2> "_enqueue_time_":1614494512538374, [junit4] 2> "nodeNames":["127.0.0.1:54115_solr"]}} [junit4] 2> 1486960 INFO (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest last action at 1518106256068000000 current time = 1518106261075000000 [junit4] 2> real diff: 5007000000 [junit4] 2> min diff: 4998000000 [junit4] 2> 1486960 INFO (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest action executed from node_lost_trigger2 [junit4] 2> 1486960 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[742DD335A645922B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTriggerThrottling [junit4] 2> 1486963 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger2 after 5000ms [junit4] 2> 1486963 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 5000ms [junit4] 2> 1486963 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_lost_trigger1 after 5000ms [junit4] 2> 1486963 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 5000ms [junit4] 2> 1486963 DEBUG (AutoscalingActionExecutor-5353-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 2 ms for event id=5bc5fcbe30509T6aqer1cm8pq30ridab7c7pynr [junit4] 2> 1486976 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEventQueue [junit4] 2> 1486977 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 7 [junit4] 2> 1486978 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7 [junit4] 2> 1486978 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7 [junit4] 2> 1486983 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 1486984 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 1486984 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7 [junit4] 2> 1486986 INFO (qtp33528256-12372) [n:127.0.0.1:54081_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1486996 INFO (qtp33528256-12372) [n:127.0.0.1:54081_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=10 [junit4] 2> 1487002 INFO (qtp33528256-12373) [n:127.0.0.1:54081_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1487005 INFO (qtp13875718-12381) [n:127.0.0.1:54087_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1 [junit4] 2> 1487006 DEBUG (qtp33528256-12369) [n:127.0.0.1:54081_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 1487007 INFO (qtp33528256-12369) [n:127.0.0.1:54081_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=9 [junit4] 2> 1487008 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8 [junit4] 2> 1487008 INFO (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest TestEventQueueAction instantiated [junit4] 2> 1487008 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:54081_solr, 127.0.0.1:54087_solr] [junit4] 2> 1487008 DEBUG (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_triggerEQ instantiated with properties: {event=nodeAdded, waitFor=1, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestEventQueueAction}], enabled=true} [junit4] 2> 1487008 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8 [junit4] 2> 1487010 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 1487011 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 1487011 DEBUG (OverseerAutoScalingTriggerThread-72163400711536646-127.0.0.1:54081_solr-n_0000000000) [n:127.0.0.1:54081_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8 [junit4] 2> 1487011 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerEQ [junit4] 2> 1487011 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 1487013 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1487013 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1487013 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1487013 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1487013 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c6c7a2{/solr,null,AVAILABLE} [junit4] 2> 1487022 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@144f755{HTTP/1.1,[http/1.1]}{127.0.0.1:54167} [junit4] 2> 1487022 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.e.j.s.Server Started @1495060ms [junit4] 2> 1487022 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54167} [junit4] 2> 1487023 ERROR (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1487023 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 1487023 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1487023 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1487023 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-08T16:11:01.138Z [junit4] 2> 1487026 INFO (zkConnectionManagerCallback-2528-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1487027 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1487042 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54074/solr [junit4] 2> 1487045 INFO (zkConnectionManagerCallback-2532-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1487048 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100603b0932000d, likely client has closed socket [junit4] 2> 1487050 INFO (zkConnectionManagerCallback-2534-thread-1-processing-n:127.0.0.1:54167_solr) [n:127.0.0.1:54167_solr ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1487059 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [n:127.0.0.1:54167_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1487062 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [n:127.0.0.1:54167_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1487065 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [n:127.0.0.1:54167_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54167_solr [junit4] 2> 1487066 INFO (zkCallback-2505-thread-1-processing-n:127.0.0.1:54081_solr) [n:127.0.0.1:54081_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1487066 INFO (zkCallback-2515-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1487066 INFO (zkCallback-2503-thread-1-processing-n:127.0.0.1:54087_solr) [n:127.0.0.1:54087_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1487068 INFO (zkCallback-2533-thread-1-processing-n:127.0.0.1:54167_solr) [n:127.0.0.1:54167_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1487302 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [n:127.0.0.1:54167_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54167.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1487313 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [n:127.0.0.1:54167_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54167.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1487313 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [n:127.0.0.1:54167_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54167.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1056a39 [junit4] 2> 1487315 INFO (TEST-TriggerIntegrationTest.testEventQueue-seed#[742DD335A645922B]) [n:127.0.0.1:54167_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_742DD335A645922B-001\tempDir-001\node4\. [junit4] 2> 1488013 DEBUG (ScheduledTrigger-5352-thread-1) [n:127.0.0.1:54081_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_triggerEQ [junit4] 2> 1488013 DEBUG (Scheduled [...truncated too long message...] t, RecoveryZkTest, TestSchemaSimilarityResource, NumericFieldsTest, TestTlogReplica, TestImpersonationWithHadoopAuth, DistributedVersionInfoTest, TestCollationField, TestMaxTokenLenTokenizer, TestLargeCluster, TestReplicationHandlerBackup, RecoveryAfterSoftCommitTest, TestSegmentSorting, AutoscalingHistoryHandlerTest, TestIBSimilarityFactory, DistributedQueryElevationComponentTest, DistributedQueryComponentOptimizationTest, TestInPlaceUpdatesStandalone, TestExecutePlanAction, TestSubQueryTransformer, TestNodeLostTrigger, TestPivotHelperCode, UninvertDocValuesMergePolicyTest, TestFaceting, ComputePlanActionTest, ResourceLoaderTest, HdfsChaosMonkeyNothingIsSafeTest, AssignBackwardCompatibilityTest, DistanceUnitsTest, TestClassicSimilarityFactory, TestApiFramework, TestCollapseQParserPlugin, ConnectionReuseTest, TestComplexPhraseQParserPlugin, TestNestedDocsSort, SolrTestCaseJ4Test, TestLeaderInitiatedRecoveryThread, SolrMetricReporterTest, OutputWriterTest, TestGraphTermsQParserPlugin, CurrencyFieldTypeTest, TestFiltering, FieldMutatingUpdateProcessorTest, TestPerFieldSimilarity, SchemaApiFailureTest, SOLR749Test, RuleEngineTest, TestHighlightDedupGrouping, OutOfBoxZkACLAndCredentialsProvidersTest, TestRemoteStreaming, TestSolrCloudWithDelegationTokens, LeaderFailureAfterFreshStartTest, JvmMetricsTest, TestSolrDeletionPolicy1, OverseerTaskQueueTest, SearchHandlerTest, TestManagedSynonymGraphFilterFactory, TestRTimerTree, TestDynamicLoading, CoreSorterTest, ReplicaListTransformerTest, TestDynamicFieldCollectionResource, SolrJmxReporterCloudTest, ZkStateWriterTest, DeleteInactiveReplicaTest, DistributedExpandComponentTest, MultiThreadedOCPTest, BinaryUpdateRequestHandlerTest, TestJmxIntegration, TestManagedStopFilterFactory, TriggerIntegrationTest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TriggerIntegrationTest -Dtests.seed=742DD335A645922B -Dtests.slow=true -Dtests.locale=ar-TN -Dtests.timezone=America/Anchorage -Dtests.asserts=true -Dtests.file.encoding=Cp1252 [junit4] ERROR 0.00s J0 | TriggerIntegrationTest (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest: [junit4] > 1) Thread[id=12865, name=qtp15834114-12865, state=TIMED_WAITING, group=TGRP-TriggerIntegrationTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([742DD335A645922B]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=12865, name=qtp15834114-12865, state=TIMED_WAITING, group=TGRP-TriggerIntegrationTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) [junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([742DD335A645922B]:0) [junit4] Completed [236/774 (2!)] on J0 in 262.49s, 14 tests, 2 errors <<< FAILURES! [...truncated 4099 lines...] [junit4] Suite: org.apache.solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\init-core-data-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=354, maxMBSortInHeap=7.7116155623085145, sim=RandomSimilarity(queryNorm=false): {}, locale=mt, timezone=Asia/Riyadh [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=21673008,total=49283072 [junit4] 2> NOTE: All tests run in this JVM: [TestFoldingMultitermExtrasQuery, TestICUCollationFieldOptions, TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory -Dtests.seed=584A1A145388B31B -Dtests.slow=true -Dtests.locale=mt -Dtests.timezone=Asia/Riyadh -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J1 | TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory (suite) <<< [junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts): [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1 [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001 [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-sent.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-sent.bin [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-tokenizer.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-tokenizer.bin [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-ner-person.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001\tempDir-001\collection1\conf\en-test-ner-person.bin [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_584A1A145388B31B-001 [junit4] > at __randomizedtesting.SeedInfo.seed([584A1A145388B31B]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [5/5 (1!)] on J1 in 1.82s, 8 tests, 1 error <<< FAILURES! [...truncated 41566 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org