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: [email protected]
For additional commands, e-mail: [email protected]
