Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/472/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseSerialGC
5 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.EchoParamsTest
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.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-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.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001
at __randomizedtesting.SeedInfo.seed([A32C490431C1F1C2]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
at
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
at
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue
Error Message:
action wasn't interrupted
Stack Trace:
java.lang.AssertionError: action wasn't interrupted
at
__randomizedtesting.SeedInfo.seed([A32C490431C1F1C2:6A990BAA38A63737]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue(TriggerIntegrationTest.java:726)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.rest.schema.TestBulkSchemaAPI
Error Message:
1 thread leaked from SUITE scope at
org.apache.solr.rest.schema.TestBulkSchemaAPI: 1) Thread[id=12013,
name=qtp19872476-12013, state=TIMED_WAITING, group=TGRP-TestBulkSchemaAPI]
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.rest.schema.TestBulkSchemaAPI:
1) Thread[id=12013, name=qtp19872476-12013, state=TIMED_WAITING,
group=TGRP-TestBulkSchemaAPI]
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([A32C490431C1F1C2]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.rest.schema.TestBulkSchemaAPI
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=12013, name=qtp19872476-12013, state=TIMED_WAITING,
group=TGRP-TestBulkSchemaAPI] 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=12013, name=qtp19872476-12013, state=TIMED_WAITING,
group=TGRP-TestBulkSchemaAPI]
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([A32C490431C1F1C2]: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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-001
at __randomizedtesting.SeedInfo.seed([1C7690B804FAC724]: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 12131 lines...]
[junit4] Suite: org.apache.solr.EchoParamsTest
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001
[junit4] 2> 21811 WARN
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 21811 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 21818 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 21819 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 21819 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 21820 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 21898 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.3.0
[junit4] 2> 21916 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 21922 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 22015 WARN
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via
JMX.
[junit4] 2> 22015 WARN
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via
JMX.
[junit4] 2> 22015 WARN
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via
JMX.
[junit4] 2> 22018 INFO (coreLoadExecutor-57-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 22018 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 22090 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 22103 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 22108 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 22108 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
instancedir
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1,
trusted=true
[junit4] 2> 22109 WARN (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via
JMX.
[junit4] 2> 22109 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 22109 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001\]
[junit4] 2> 22113 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=31, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=99.5078125,
floorSegmentMB=1.5859375, forceMergeDeletesPctAllowed=20.633072445564178,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 22146 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=60
[junit4] 2> 22227 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 22227 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 22229 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7040324949807278]
[junit4] 2> 22229 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@b452fa[collection1] main]
[junit4] 2> 22230 WARN (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.r.ManagedResourceStorage Cannot write to config directory
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf;
switching to use InMemory storage instead.
[junit4] 2> 22231 INFO (coreLoadExecutor-57-thread-1) [ x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 22232 INFO
(searcherExecutor-58-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@b452fa[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 22236 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 22238 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2])
[ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 22253 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=foo&qt=&start=0&rows=20&version=2.2&wt=xml} hits=0 status=0 QTime=15
[junit4] 2> 22260 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=foo&qt=&start=0&rows=20&version=2.2&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 22260 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=foo&qt=&start=0&rows=20&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 22261 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=foo&qt=&start=0&rows=20&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 22263 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=foo&qt=&echoParams=explicit&start=0&rows=20&wt=xml&version=2.2}
hits=0 status=0 QTime=0
[junit4] 2> 22263 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=foo&qt=&echoParams=explicit&start=0&rows=20&wt=xml&version=2.2}
hits=0 status=0 QTime=0
[junit4] 2> 22264 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=foo&qt=&echoParams=explicit&start=0&rows=20&wt=xml&version=2.2}
hits=0 status=0 QTime=0
[junit4] 2> 22265 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={echoHandler=true&q=foo&qt=/crazy_custom_qt&echoParams=all&start=0&rows=20&version=2.2&wt=xml}
hits=0 status=0 QTime=0
[junit4] 2> 22266 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2])
[ ] o.a.s.c.S.Request [collection1] webapp=null path=null
params={echoHandler=true&q=foo&qt=/crazy_custom_qt&echoParams=all&start=0&rows=20&version=2.2&wt=xml}
hits=0 status=0 QTime=0
[junit4] 2> 22266 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2])
[ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 22266 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 22266 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=427348
[junit4] 2> 22267 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 22267 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3cfbc: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ null
[junit4] 2> 22267 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 22267 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5c3dd9: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
null
[junit4] 2> 22267 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 22267 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@52bcc5: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / null
[junit4] 2> 22268 INFO (coreCloseExecutor-62-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1ac999b
[junit4] 2> 22268 INFO (coreCloseExecutor-62-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=28088731
[junit4] 2> 22268 INFO (coreCloseExecutor-62-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@11cb92c: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / null
[junit4] 2> 22271 INFO
(SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=RandomSimilarity(queryNorm=false): {}, locale=nn-NO, timezone=NZ
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144
(32-bit)/cpus=3,threads=1,free=19361856,total=51142656
[junit4] 2> NOTE: All tests run in this JVM:
[DocExpirationUpdateProcessorFactoryTest, TestUseDocValuesAsStored2,
TestManagedResourceStorage, TestDynamicFieldCollectionResource,
ActionThrottleTest, ExternalFileFieldSortTest, EchoParamsTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=EchoParamsTest
-Dtests.seed=A32C490431C1F1C2 -Dtests.slow=true -Dtests.locale=nn-NO
-Dtests.timezone=NZ -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | EchoParamsTest (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.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001
[junit4] > at
__randomizedtesting.SeedInfo.seed([A32C490431C1F1C2]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [8/777 (1!)] on J1 in 0.49s, 1 test, 1 error <<< FAILURES!
[...truncated 471 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\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\init-core-data-001
[junit4] 2> 330158 WARN
(SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 330158 INFO
(SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 330162 INFO
(SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-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> 330162 INFO
(SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 330164 INFO
(SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-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\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\tempDir-001
[junit4] 2> 330164 INFO
(SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 330164 INFO (Thread-642) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 330164 INFO (Thread-642) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 330168 ERROR (Thread-642) [ ] 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> 330264 INFO
(SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:58093
[junit4] 2> 330270 INFO (zkConnectionManagerCallback-646-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330274 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
0x100af03f59d0000, likely client has closed socket
[junit4] 2> 330277 INFO (jetty-launcher-643-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 330277 INFO (jetty-launcher-643-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 330277 INFO (jetty-launcher-643-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 330277 INFO (jetty-launcher-643-thread-2) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 330277 INFO (jetty-launcher-643-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@fc5dea{/solr,null,AVAILABLE}
[junit4] 2> 330279 INFO (jetty-launcher-643-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 330279 INFO (jetty-launcher-643-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 330279 INFO (jetty-launcher-643-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 330279 INFO (jetty-launcher-643-thread-1) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 330279 INFO (jetty-launcher-643-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1d21846{/solr,null,AVAILABLE}
[junit4] 2> 330284 INFO (jetty-launcher-643-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1ebd725{HTTP/1.1,[http/1.1]}{127.0.0.1:58100}
[junit4] 2> 330284 INFO (jetty-launcher-643-thread-1) [ ]
o.e.j.s.Server Started @337724ms
[junit4] 2> 330284 INFO (jetty-launcher-643-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58100}
[junit4] 2> 330285 ERROR (jetty-launcher-643-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-23T23:20:40.472Z
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5b20c3{HTTP/1.1,[http/1.1]}{127.0.0.1:58097}
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ]
o.e.j.s.Server Started @337725ms
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58097}
[junit4] 2> 330285 ERROR (jetty-launcher-643-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-23T23:20:40.472Z
[junit4] 2> 330291 INFO (zkConnectionManagerCallback-648-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330292 INFO (zkConnectionManagerCallback-650-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330293 INFO (jetty-launcher-643-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 330293 INFO (jetty-launcher-643-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 330306 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
0x100af03f59d0002, likely client has closed socket
[junit4] 2> 330312 INFO (jetty-launcher-643-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58093/solr
[junit4] 2> 330498 INFO (zkConnectionManagerCallback-654-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330503 INFO (jetty-launcher-643-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58093/solr
[junit4] 2> 330505 INFO
(zkConnectionManagerCallback-656-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330510 INFO (zkConnectionManagerCallback-660-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330513 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
0x100af03f59d0005, likely client has closed socket
[junit4] 2> 330529 INFO
(zkConnectionManagerCallback-662-thread-1-processing-n:127.0.0.1:58097_solr)
[n:127.0.0.1:58097_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330768 INFO (jetty-launcher-643-thread-2)
[n:127.0.0.1:58097_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 330768 INFO (jetty-launcher-643-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 330769 INFO (jetty-launcher-643-thread-2)
[n:127.0.0.1:58097_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:58097_solr
[junit4] 2> 330770 INFO (jetty-launcher-643-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58100_solr
[junit4] 2> 330771 INFO (jetty-launcher-643-thread-2)
[n:127.0.0.1:58097_solr ] o.a.s.c.Overseer Overseer
(id=72250025578397702-127.0.0.1:58097_solr-n_0000000000) starting
[junit4] 2> 330788 INFO (jetty-launcher-643-thread-2)
[n:127.0.0.1:58097_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58097_solr
[junit4] 2> 330972 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 330980 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 330980 INFO
(zkCallback-655-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 330981 INFO
(zkCallback-661-thread-1-processing-n:127.0.0.1:58097_solr)
[n:127.0.0.1:58097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 330982 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:58100_solr, 127.0.0.1:58097_solr]
[junit4] 2> 330982 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 330982 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 330989 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 330993 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 331062 INFO (jetty-launcher-643-thread-2)
[n:127.0.0.1:58097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58097.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331071 INFO (jetty-launcher-643-thread-2)
[n:127.0.0.1:58097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58097.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331071 INFO (jetty-launcher-643-thread-2)
[n:127.0.0.1:58097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58097.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331079 INFO (jetty-launcher-643-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58100.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331093 INFO (jetty-launcher-643-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58100.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331093 INFO (jetty-launcher-643-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58100.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331094 INFO (jetty-launcher-643-thread-2)
[n:127.0.0.1:58097_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\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\tempDir-001\node2\.
[junit4] 2> 331096 INFO (jetty-launcher-643-thread-1)
[n:127.0.0.1:58100_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\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\tempDir-001\node1\.
[junit4] 2> 331098 DEBUG (ScheduledTrigger-1494-thread-1)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 331424 INFO (zkConnectionManagerCallback-668-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 331433 INFO (zkConnectionManagerCallback-672-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 331434 INFO
(SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 331435 INFO
(SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58093/solr ready
[junit4] 2> 331452 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.SolrTestCaseJ4 ###Starting testTriggerThrottling
[junit4] 2> 331456 INFO (qtp5089530-3036) [n:127.0.0.1:58097_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 331473 INFO (qtp5089530-3036) [n:127.0.0.1:58097_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=17
[junit4] 2> 331475 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.a.ScheduledTriggers -- removing trigger: .auto_add_replicas
[junit4] 2> 331481 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2
[junit4] 2> 331484 DEBUG
(zkCallback-661-thread-1-processing-n:127.0.0.1:58097_solr)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 2
[junit4] 2> 331484 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 2
[junit4] 2> 331484 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeLost markers
[junit4] 2> 331485 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 331485 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 331485 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 331487 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.AbstractConnector Stopped
ServerConnector@5b20c3{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 331488 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=20519641
[junit4] 2> 331489 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 331489 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1a4a5e9: rootName =
solr_58097, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@e76f1f
[junit4] 2> 331494 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 331494 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@19f7f7b: rootName =
solr_58097, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@5e1a7b
[junit4] 2> 331498 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 331498 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4bc6a9: rootName =
solr_58097, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@1c11675
[junit4] 2> 331500 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:58097_solr
[junit4] 2> 331503 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.cluster, tag=null
[junit4] 2> 331503 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 331504 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.Overseer Overseer
(id=72250025578397702-127.0.0.1:58097_solr-n_0000000000) closing
[junit4] 2> 331505 INFO
(OverseerStateUpdate-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:58097_solr
[junit4] 2> 331508 DEBUG
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed
explicitly
[junit4] 2> 331509 WARN
(OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000)
[n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 331512 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
0x100af03f59d0006, likely client has closed socket
[junit4] 2> 331512 INFO
(zkCallback-661-thread-1-processing-n:127.0.0.1:58097_solr)
[n:127.0.0.1:58097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 331512 INFO
(zkCallback-655-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 331513 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@fc5dea{/solr,null,UNAVAILABLE}
[junit4] 2> 331513 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.session Stopped scavenging
[junit4] 2> 331513 INFO (zkCallback-671-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 331514 INFO (zkCallback-671-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 331515 INFO
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:58100_solr
[junit4] 2> 331515 INFO
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.Overseer Overseer
(id=72250025578397700-127.0.0.1:58100_solr-n_0000000001) starting
[junit4] 2> 331525 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 331527 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 3
[junit4] 2> 331527 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:58100_solr]
[junit4] 2> 331527 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion -1
[junit4] 2> 331528 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 3
[junit4] 2> 331529 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 331531 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 331531 DEBUG (ScheduledTrigger-1503-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 332533 DEBUG (ScheduledTrigger-1503-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 333534 DEBUG (ScheduledTrigger-1503-thread-2)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 334536 DEBUG (ScheduledTrigger-1503-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 335537 DEBUG (ScheduledTrigger-1503-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 336517 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 336519 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 336519 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 336519 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 336521 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1c341b0{/solr,null,AVAILABLE}
[junit4] 2> 336522 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.AbstractConnector Started
ServerConnector@17ab75a{HTTP/1.1,[http/1.1]}{127.0.0.1:58211}
[junit4] 2> 336522 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.Server Started @343962ms
[junit4] 2> 336522 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58211}
[junit4] 2> 336522 ERROR
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 336522 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 336522 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
null
[junit4] 2> 336522 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 336522 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-23T23:20:46.709Z
[junit4] 2> 336527 INFO (zkConnectionManagerCallback-675-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336528 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 336544 DEBUG (ScheduledTrigger-1503-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 1
[junit4] 2> 336549 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58093/solr
[junit4] 2> 336551 INFO (zkConnectionManagerCallback-679-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336556 INFO
(zkConnectionManagerCallback-681-thread-1-processing-n:127.0.0.1:58211_solr)
[n:127.0.0.1:58211_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336565 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58211_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 336566 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58211_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 336572 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58211_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58211_solr
[junit4] 2> 336575 INFO (zkCallback-671-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 336575 INFO (zkCallback-671-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 336575 INFO
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 336576 INFO
(zkCallback-680-thread-1-processing-n:127.0.0.1:58211_solr)
[n:127.0.0.1:58211_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 336717 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58211.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 336735 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58211.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 336735 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58211.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 336738 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58211_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\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\tempDir-001\node3\.
[junit4] 2> 336790 INFO (qtp24431827-3040) [n:127.0.0.1:58100_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> 336794 INFO (qtp4875339-3109) [n:127.0.0.1:58211_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> 336795 DEBUG (qtp24431827-3045) [n:127.0.0.1:58100_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 336796 INFO (qtp24431827-3045) [n:127.0.0.1:58100_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 336796 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 4
[junit4] 2> 336797 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr]
[junit4] 2> 336798 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr]
[junit4] 2> 336798 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_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> 336798 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 4
[junit4] 2> 336801 INFO (qtp24431827-3040) [n:127.0.0.1:58100_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> 336803 INFO (qtp4875339-3111) [n:127.0.0.1:58211_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> 336804 DEBUG (qtp24431827-3042) [n:127.0.0.1:58100_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 336804 INFO (qtp24431827-3042) [n:127.0.0.1:58100_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 336804 INFO
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 336805 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 5
[junit4] 2> 336805 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr]
[junit4] 2> 336805 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_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> 336805 DEBUG (ScheduledTrigger-1503-thread-3)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger1
[junit4] 2> 336805 DEBUG (ScheduledTrigger-1503-thread-3)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 336805 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr]
[junit4] 2> 336806 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr]
[junit4] 2> 336806 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_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> 336806 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 5, lastZnodeVersion 4
[junit4] 2> 336806 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 5
[junit4] 2> 336807 INFO
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 336807 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 336807 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 336807 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger2
[junit4] 2> 336807 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 336807 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 336807 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 336807 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 336811 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@19c7009{/solr,null,AVAILABLE}
[junit4] 2> 336811 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.AbstractConnector Started
ServerConnector@15fef79{HTTP/1.1,[http/1.1]}{127.0.0.1:58226}
[junit4] 2> 336811 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.e.j.s.Server Started @344251ms
[junit4] 2> 336811 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58226}
[junit4] 2> 336811 ERROR
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 336811 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 336811 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
null
[junit4] 2> 336811 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 336811 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-23T23:20:46.998Z
[junit4] 2> 336814 INFO (zkConnectionManagerCallback-685-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336815 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 336827 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
0x100af03f59d000c, likely client has closed socket
[junit4] 2> 336831 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58093/solr
[junit4] 2> 336833 INFO (zkConnectionManagerCallback-689-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336837 INFO
(zkConnectionManagerCallback-691-thread-1-processing-n:127.0.0.1:58226_solr)
[n:127.0.0.1:58226_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336847 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58226_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 336850 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58226_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 336854 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58226_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58226_solr
[junit4] 2> 336856 INFO
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 336856 INFO (zkCallback-671-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 336857 INFO
(zkCallback-680-thread-1-processing-n:127.0.0.1:58211_solr)
[n:127.0.0.1:58211_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 336857 INFO (zkCallback-671-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 336858 INFO
(zkCallback-690-thread-1-processing-n:127.0.0.1:58226_solr)
[n:127.0.0.1:58226_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 337219 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58226_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58226.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 337229 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58226_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58226.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 337229 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58226_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58226.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 337231 INFO
(TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2])
[n:127.0.0.1:58226_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\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\tempDir-001\node4\.
[junit4] 2> 337546 DEBUG (ScheduledTrigger-1503-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 337808 DEBUG (ScheduledTrigger-1503-thread-2)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger1
[junit4] 2> 337809 DEBUG (ScheduledTrigger-1503-thread-2)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger2
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:58226_solr at time 2936279930489039
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger2 firing registered processor for nodes:
[127.0.0.1:58226_solr] added at times [2936279930489039], now=2936279930538248
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"a6e87f237c8cfT732ukvjnyoqcuvvfgi0hwiz95",
[junit4] 2> "source":"node_added_trigger2",
[junit4] 2> "eventTime":2936279930489039,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2936279930489039],
[junit4] 2> "nodeNames":["127.0.0.1:58226_solr"]}}
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-2)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node:
127.0.0.1:58226_solr at time 2936279930504399
[junit4] 2> 337813 DEBUG (ScheduledTrigger-1503-thread-2)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger1 firing registered processor for nodes:
[127.0.0.1:58226_solr] added at times [2936279930504399], now=2936279931407789
[junit4] 2> 337813 DEBUG (ScheduledTrigger-1503-thread-2)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"a6e87f23804cfT732ukvjnyoqcuvvfgi0hwiz96",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":2936279930504399,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2936279930504399],
[junit4] 2> "nodeNames":["127.0.0.1:58226_solr"]}}
[junit4] 2> 337813 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[.auto_add_replicas, node_added_trigger2, node_added_trigger1]
[junit4] 2> 337813 INFO (ScheduledTrigger-1503-thread-2)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 337815 INFO (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.SystemLogListener Collection .system
does not exist, disabling logging.
[junit4] 2> 337819 DEBUG
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"a6e87f237c8cfT732ukvjnyoqcuvvfgi0hwiz95",
[junit4] 2> "source":"node_added_trigger2",
[junit4] 2> "eventTime":2936279930489039,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2936279930489039],
[junit4] 2> "_enqueue_time_":2936279931752249,
[junit4] 2> "nodeNames":["127.0.0.1:58226_solr"]}}
[junit4] 2> 337819 INFO
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest action executed
from node_added_trigger2
[junit4] 2> 337822 DEBUG
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
.auto_add_replicas after 5000ms
[junit4] 2> 337822 DEBUG
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_trigger2 after 5000ms
[junit4] 2> 337822 DEBUG
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_trigger1 after 5000ms
[junit4] 2> 337822 DEBUG
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 4
ms for event id=a6e87f237c8cfT732ukvjnyoqcuvvfgi0hwiz95
[junit4] 2> 342823 DEBUG (ScheduledTrigger-1503-thread-1)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 3
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger1
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-2)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running
NodeAddedTrigger node_added_trigger2
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-2)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger
node_added_trigger1 firing registered processor for nodes:
[127.0.0.1:58226_solr] added at times [2936279930504399], now=2936284942598901
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown
inactive - processing event: {
[junit4] 2> "id":"a6e87f23804cfT732ukvjnyoqcuvvfgi0hwiz99",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":2936279930504399,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2936279930504399],
[junit4] 2> "nodeNames":["127.0.0.1:58226_solr"]}}
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-4)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers:
[.auto_add_replicas, node_added_trigger2, node_added_trigger1]
[junit4] 2> 342825 DEBUG
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions
for {
[junit4] 2> "id":"a6e87f23804cfT732ukvjnyoqcuvvfgi0hwiz99",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":2936279930504399,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2936279930504399],
[junit4] 2> "_enqueue_time_":2936284942962419,
[junit4] 2> "nodeNames":["127.0.0.1:58226_solr"]}}
[junit4] 2> 342825 INFO
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest last action at
1519428048006000000 current time = 1519428053012000000
[junit4] 2> real diff: 5006000000
[junit4] 2> min diff: 4998000000
[junit4] 2> 342825 INFO
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest action executed
from node_added_trigger1
[junit4] 2> 342827 DEBUG
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
.auto_add_replicas after 5000ms
[junit4] 2> 342827 DEBUG
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_trigger2 after 5000ms
[junit4] 2> 342827 DEBUG
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger:
node_added_trigger1 after 5000ms
[junit4] 2> 342827 DEBUG
(AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 1
ms for event id=a6e87f23804cfT732ukvjnyoqcuvvfgi0hwiz99
[junit4] 2> 342831 INFO (qtp24431827-3042) [n:127.0.0.1:58100_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> 342834 INFO (qtp4875339-3113) [n:127.0.0.1:58211_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> 342840 INFO (qtp1650034-3135) [n:127.0.0.1:58226_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> 342841 DEBUG (qtp24431827-3039) [n:127.0.0.1:58100_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 342842 INFO (qtp24431827-3039) [n:127.0.0.1:58100_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 342842 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 6
[junit4] 2> 342843 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342843 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_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> 342843 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342843 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342843 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_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> 342843 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342844 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 6
[junit4] 2> 342846 INFO
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest
TestTriggerAction init
[junit4] 2> 342847 INFO (qtp24431827-3039) [n:127.0.0.1:58100_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=2
[junit4] 2> 342848 DEBUG
(OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 342848 DEBUG (ScheduledTrigger-1503-thread-3)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
node_lost_trigger1 with currently live nodes: 3
[junit4] 2> 342851 INFO (qtp4875339-3114) [n:127.0.0.1:58211_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> 342853 INFO (qtp1650034-3141) [n:127.0.0.1:58226_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> 342855 DEBUG (qtp4875339-3113) [n:127.0.0.1:58211_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 342855 INFO (qtp4875339-3113) [n:127.0.0.1:58211_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 342855 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 7
[junit4] 2> 342856 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342856 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_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> 342856 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342857 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes:
[127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342857 DEBUG
(zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr)
[n:127.0.0.1:58100_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> 342857 DEBUG (zkCallback-655-thread-2-processin
[...truncated too long message...]
yticsQueryTest, SubstringBytesRefFilterTest, TestStressRecovery,
OverseerStatusTest, TestTlogReplica, TestSimDistributedQueue,
TestSolrCoreSnapshots, SuggestComponentTest, DeleteStatusTest,
TestLargeCluster, TestXmlQParser, PeerSyncReplicationTest,
TestReRankQParserPlugin, PeerSyncWithIndexFingerprintCachingTest,
TestCollationFieldDocValues, ExitableDirectoryReaderTest,
TestSerializedLuceneMatchVersion, LIRRollingUpdatesTest, TestOrdValues,
TestFieldSortValues, TestStressUserVersions, JsonLoaderTest,
BasicDistributedZk2Test, TestStressReorder, TestSolr4Spatial2,
QueryParsingTest, TestFieldCache, SimpleFacetsTest,
AutoAddReplicasPlanActionTest, TestWriterPerf, FullSolrCloudDistribCmdsTest,
HdfsChaosMonkeyNothingIsSafeTest, ResponseLogComponentTest,
TestSQLHandlerNonCloud, ClusterStateTest, TestUnifiedSolrHighlighter,
TestPayloadScoreQParserPlugin, QueryResultKeyTest, BufferStoreTest,
AddBlockUpdateTest, DistributedSpellCheckComponentTest,
DeleteLastCustomShardedReplicaTest, CustomHighlightComponentTest,
MoveReplicaHDFSFailoverTest, DirectUpdateHandlerTest,
CachingDirectoryFactoryTest, TestDFRSimilarityFactory,
CollectionStateFormat2Test, ConcurrentDeleteAndCreateCollectionTest,
DistributedFacetPivotWhiteBoxTest, SystemInfoHandlerTest, ZkSolrClientTest,
NodeMutatorTest, HttpPartitionOnCommitTest, TestScoreJoinQPScore,
TestFieldCollectionResource, TestBlobHandler, XsltUpdateRequestHandlerTest,
SpellPossibilityIteratorTest, CdcrUpdateLogTest, ScriptEngineTest,
ShowFileRequestHandlerTest, TestSolrIndexConfig, TestSizeLimitedDistributedMap,
TestCollapseQParserPlugin, HighlighterConfigTest, TestValueSourceCache,
DistributedQueueTest, TestCrossCoreJoin, TestJsonFacets,
BasicFunctionalityTest, HLLSerializationTest, SpatialHeatmapFacetsTest,
TestRandomDVFaceting, TestBulkSchemaAPI]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBulkSchemaAPI
-Dtests.seed=A32C490431C1F1C2 -Dtests.slow=true -Dtests.locale=es-PE
-Dtests.timezone=Asia/Kathmandu -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestBulkSchemaAPI (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.rest.schema.TestBulkSchemaAPI:
[junit4] > 1) Thread[id=12013, name=qtp19872476-12013,
state=TIMED_WAITING, group=TGRP-TestBulkSchemaAPI]
[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([A32C490431C1F1C2]:0)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=12013, name=qtp19872476-12013,
state=TIMED_WAITING, group=TGRP-TestBulkSchemaAPI]
[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([A32C490431C1F1C2]:0)
[junit4] Completed [280/777 (3!)] on J1 in 98.59s, 10 tests, 2 errors <<<
FAILURES!
[...truncated 3991 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_1C7690B804FAC724-001\init-core-data-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1294, maxMBSortInHeap=7.981586594514307,
sim=RandomSimilarity(queryNorm=true): {}, locale=el-CY, timezone=Asia/Urumqi
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144
(32-bit)/cpus=3,threads=1,free=12950296,total=42663936
[junit4] 2> NOTE: All tests run in this JVM:
[TestFoldingMultitermExtrasQuery, TestICUCollationFieldOptions,
TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory
-Dtests.seed=1C7690B804FAC724 -Dtests.slow=true -Dtests.locale=el-CY
-Dtests.timezone=Asia/Urumqi -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-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_1C7690B804FAC724-001
[junit4] > at
__randomizedtesting.SeedInfo.seed([1C7690B804FAC724]: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.15s, 8 tests, 1 error <<< FAILURES!
[...truncated 43379 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
