Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7138/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseG1GC

3 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.lucene.store.TestNIOFSDirectory

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNIOFSDirectory_B2B91EB243D705CA-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNIOFSDirectory_B2B91EB243D705CA-001\tempDir-001
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNIOFSDirectory_B2B91EB243D705CA-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNIOFSDirectory_B2B91EB243D705CA-001\tempDir-001

        at __randomizedtesting.SeedInfo.seed([B2B91EB243D705CA]: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.testListeners

Error Message:


Stack Trace:
java.lang.AssertionError
        at 
__randomizedtesting.SeedInfo.seed([C720E35CFDD4A142:2AE0744C40B83C9B]:0)
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertTrue(Assert.java:54)
        at 
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testListeners(TriggerIntegrationTest.java:1053)
        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.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf
    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1
    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001
    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-tokenizer.bin:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-tokenizer.bin
    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-ner-person.bin:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-ner-person.bin
    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-sent.bin:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-sent.bin
    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf
   
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1
   
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001
   
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-tokenizer.bin:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-tokenizer.bin
   
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-ner-person.bin:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-ner-person.bin
   
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-sent.bin:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-sent.bin
   
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001

        at __randomizedtesting.SeedInfo.seed([208ABB6A55DC50C2]: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 1721 lines...]
   [junit4] Suite: org.apache.lucene.store.TestNIOFSDirectory
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=668, maxMBSortInHeap=6.931506918883576, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@e316b7),
 locale=en-ZA, timezone=Europe/Amsterdam
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 
(32-bit)/cpus=3,threads=1,free=168043144,total=502267904
   [junit4]   2> NOTE: All tests run in this JVM: [TestTimSorterWorstCase, 
TestIndexTooManyDocs, TestSleepingLockWrapper, TestCheckIndex, 
TestAxiomaticF2EXP, TestStressAdvance, TestSegmentInfos, 
TestNormsFieldExistsQuery, TestRollingBuffer, TestSimilarityBase, 
TestMultiCollector, TestPayloads, TestSloppyPhraseQuery2, TestMergeRateLimiter, 
TestNumericDocValuesUpdates, Test2BSortedDocValuesFixedSorted, 
TestNoDeletionPolicy, TestTwoPhaseCommitTool, TestSpanContainQuery, 
TestCustomSearcherSort, TestDateTools, TestReqOptSumScorer, TestRollingUpdates, 
TestBytesRefHash, Test2BPostings, TestIndexWriterOutOfFileDescriptors, 
TestUsageTrackingFilterCachingPolicy, TestTermsEnum2, 
TestPersistentSnapshotDeletionPolicy, TestIndexWriterOnDiskFull, 
TestLMJelinekMercerSimilarity, TestBasics, TestDeterminism, TestIndexedDISI, 
TestApproximationSearchEquivalence, TestStringMSBRadixSorter, 
TestIndependenceStandardized, Test2BDocs, TestMergeSchedulerExternal, 
MultiCollectorTest, TestSizeBoundedForceMerge, TestNRTCachingDirectory, 
TestNewestSegment, TestNot, TestGraphTokenStreamFiniteStrings, TestDocValues, 
TestPrefixInBooleanQuery, TestLucene50StoredFieldsFormatHighCompression, 
TestDoubleValuesSource, TestStressIndexing2, TestBinaryDocument, 
TestMultiPhraseQuery, TestCustomTermFreq, TestDocValuesRewriteMethod, 
TestIndependenceChiSquared, TestIndexWriterForceMerge, TestSloppyMath, 
TestTermVectorsReader, TestBytesRefAttImpl, TestIndexWriterMaxDocs, 
TestLucene70NormsFormat, TestRecyclingIntBlockAllocator, 
TestSimpleExplanations, TestShardSearching, TestTryDelete, TestBytesRefArray, 
TestBoostQuery, TestAxiomaticF1EXP, TestWildcard, TestForTooMuchCloning, 
TestIntroSelector, TestPhrasePrefixQuery, TestSortedNumericSortField, 
TestAllFilesHaveChecksumFooter, TestBoolean2, TestPositiveScoresOnlyCollector, 
TestCodecHoldsOpenFiles, TestIndexWriterReader, TestFieldValueQuery, 
TestSpanTermQuery, TestTermVectorsWriter, TestNRTReaderWithThreads, 
TestNoMergePolicy, FuzzyTermOnShortTermsTest, TestFilterIterator, 
TestLucene60FieldInfoFormat, TestRegexpRandom, TestRecyclingByteBlockAllocator, 
TestSpanBoostQuery, TestSpanNotQuery, TestMultiTermsEnum, 
TestSortedSetSortField, TestBoolean2ScorerSupplier, TestSegmentTermDocs, 
TestFixedBitSet, TestAxiomaticF3EXP, TestVirtualMethod, 
TestPerFieldPostingsFormat, TestTermsEnum, TestFileSwitchDirectory, TestTerms, 
TestBasicModelIn, TestRamUsageEstimator, TestControlledRealTimeReopenThread, 
TestCharArraySet, TestBufferedChecksum, TestPerFieldDocValuesFormat, 
TestBlockPostingsFormat3, TestTermRangeQuery, TestDocumentsWriterStallControl, 
TestSimpleAttributeImpl, TestDisjunctionMaxQuery, TestSimpleFSLockFactory, 
TestTermQuery, TestGeoUtils, TestTransactions, TestSimpleSearchEquivalence, 
TestFixedLengthBytesRefArray, TestConcurrentMergeScheduler, TestDeletionPolicy, 
TestNRTThreads, TestDuelingCodecs, TestIndexWriterCommit, 
TestDirectoryReaderReopen, TestTimeLimitingCollector, TestSearchAfter, 
TestHighCompressionMode, TestNeverDelete, TestTopDocsMerge, TestLongPostings, 
TestStressIndexing, TestMinimize, TestSnapshotDeletionPolicy, 
TestIndexWriterUnicode, TestByteSlices, TestSegmentMerger, TestCollectionUtil, 
TestFastDecompressionMode, TestIndexWriterNRTIsCurrent, TestSort, 
TestMultiDocValues, TestSpans, TestPostingsOffsets, TestTransactionRollback, 
TestFlex, TestFastCompressionMode, TestLazyProxSkipping, 
TestCharTermAttributeImpl, TestParallelLeafReader, TestStressNRT, 
TestForceMergeForever, TestNearSpansOrdered, TestDocumentsWriterDeleteQueue, 
TestMultiTermConstantScore, TestFuzzyQuery, TestPerSegmentDeletes, 
TestTopDocsCollector, TestUniqueTermCount, TestSortRandom, 
TestAllFilesHaveCodecHeader, TestDocument, TestMaxTermFrequency, 
TestTermScorer, TestTopFieldCollector, TestSentinelIntSet, TestPrefixRandom, 
TestMultiLevelSkipList, TestSearchForDuplicates, TestConstantScoreQuery, 
TestPrefixQuery, TestSubScorerFreqs, TestConjunctions, TestPositionIncrement, 
TestSimilarity, TestSimilarityProvider, TestSearch, TestDocCount, 
TestAttributeSource, TestIsCurrent, TestByteBlockPool, TestDemo, TestRollback, 
TestIndexWriterOnJRECrash, Test4GBStoredFields, TestScoreCachingWrappingScorer, 
TestTermdocPerf, TestIndexCommit, TestIOUtils, TestByteArrayDataInput, 
Test2BBinaryDocValues, TestDelegatingAnalyzerWrapper, TestBlockPostingsFormat2, 
TestLucene50StoredFieldsFormat, TestLucene50TermVectorsFormat, 
Test2BSortedDocValuesOrds, TestAllFilesCheckIndexHeader, TestDocIDMerger, 
TestDocsWithFieldSet, TestDuelingCodecsAtNight, TestExitableDirectoryReader, 
TestFieldReuse, TestFilterDirectoryReader, TestIndexWriterExceptions2, 
TestIndexingSequenceNumbers, TestSortedSetDocValues, TestStressDeletes, 
TestTermStates, TestUpgradeIndexMergePolicy, TermInSetQueryTest, 
TestBlendedTermQuery, TestBooleanRewrites, TestDocValuesQueries, 
TestEarlyTermination, TestFloatRangeFieldQueries, TestIndexSearcher, 
TestLRUQueryCache, TestMinShouldMatch2, TestSortedSetSelector, 
TestSynonymQuery, TestTopFieldCollectorEarlyTermination, 
TestAxiomaticSimilarity, TestBM25Similarity, TestBasicModelIne, 
TestDistributionLL, TestIndependenceSaturated, TestNIOFSDirectory]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestNIOFSDirectory 
-Dtests.seed=B2B91EB243D705CA -Dtests.slow=true -Dtests.locale=en-ZA 
-Dtests.timezone=Europe/Amsterdam -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J1 | TestNIOFSDirectory (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the 
following files (in the order of attempts):
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNIOFSDirectory_B2B91EB243D705CA-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNIOFSDirectory_B2B91EB243D705CA-001\tempDir-001
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([B2B91EB243D705CA]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [453/477 (1!)] on J1 in 1.82s, 42 tests, 1 error <<< 
FAILURES!

[...truncated 10907 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_C720E35CFDD4A142-001\init-core-data-001
   [junit4]   2> 1062593 WARN  
(SUITE-TriggerIntegrationTest-seed#[C720E35CFDD4A142]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10
   [junit4]   2> 1062594 INFO  
(SUITE-TriggerIntegrationTest-seed#[C720E35CFDD4A142]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1062599 INFO  
(SUITE-TriggerIntegrationTest-seed#[C720E35CFDD4A142]-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> 1062599 INFO  
(SUITE-TriggerIntegrationTest-seed#[C720E35CFDD4A142]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1062601 INFO  
(SUITE-TriggerIntegrationTest-seed#[C720E35CFDD4A142]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_C720E35CFDD4A142-001\tempDir-001
   [junit4]   2> 1062601 INFO  
(SUITE-TriggerIntegrationTest-seed#[C720E35CFDD4A142]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1062603 INFO  (Thread-2254) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1062603 INFO  (Thread-2254) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1062607 ERROR (Thread-2254) [    ] 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> 1062702 INFO  
(SUITE-TriggerIntegrationTest-seed#[C720E35CFDD4A142]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:53890
   [junit4]   2> 1062706 INFO  (zkConnectionManagerCallback-2755-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1062711 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1062712 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1062712 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1062712 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 1062712 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@a6e1a5{/solr,null,AVAILABLE}
   [junit4]   2> 1062712 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1062713 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1062713 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1062713 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1062713 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6f5be6{HTTP/1.1,[http/1.1]}{127.0.0.1:53894}
   [junit4]   2> 1062713 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.e.j.s.Server Started @1071102ms
   [junit4]   2> 1062713 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=53894}
   [junit4]   2> 1062713 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@17b86f5{/solr,null,AVAILABLE}
   [junit4]   2> 1062714 ERROR (jetty-launcher-2752-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1062714 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 1062714 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1062714 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1062714 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-01-25T08:46:43.382Z
   [junit4]   2> 1062714 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@e36738{HTTP/1.1,[http/1.1]}{127.0.0.1:53897}
   [junit4]   2> 1062714 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.e.j.s.Server Started @1071103ms
   [junit4]   2> 1062714 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=53897}
   [junit4]   2> 1062714 ERROR (jetty-launcher-2752-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1062714 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 1062714 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1062714 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1062715 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-01-25T08:46:43.382Z
   [junit4]   2> 1062722 INFO  (zkConnectionManagerCallback-2759-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1062722 INFO  (zkConnectionManagerCallback-2757-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1062723 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1062723 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1062738 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x1612c7f6a4a0002, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1062738 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x1612c7f6a4a0001, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1062744 INFO  (jetty-launcher-2752-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53890/solr
   [junit4]   2> 1062744 INFO  (jetty-launcher-2752-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53890/solr
   [junit4]   2> 1062746 INFO  (zkConnectionManagerCallback-2767-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1062746 INFO  (zkConnectionManagerCallback-2765-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1062747 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x1612c7f6a4a0003, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1062750 INFO  
(zkConnectionManagerCallback-2769-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1062750 INFO  
(zkConnectionManagerCallback-2771-thread-1-processing-n:127.0.0.1:53894_solr) 
[n:127.0.0.1:53894_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1062859 INFO  (jetty-launcher-2752-thread-1) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1062860 INFO  (jetty-launcher-2752-thread-1) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:53897_solr
   [junit4]   2> 1062861 INFO  (jetty-launcher-2752-thread-1) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.Overseer Overseer 
(id=99409592534564869-127.0.0.1:53897_solr-n_0000000000) starting
   [junit4]   2> 1062866 INFO  (jetty-launcher-2752-thread-2) 
[n:127.0.0.1:53894_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1062867 INFO  (jetty-launcher-2752-thread-2) 
[n:127.0.0.1:53894_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:53894_solr
   [junit4]   2> 1062870 INFO  
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1062872 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Adding 
.autoAddReplicas trigger
   [junit4]   2> 1062872 INFO  
(zkCallback-2770-thread-1-processing-n:127.0.0.1:53894_solr) 
[n:127.0.0.1:53894_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1062873 INFO  (jetty-launcher-2752-thread-1) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:53897_solr
   [junit4]   2> 1062874 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 1
   [junit4]   2> 1062881 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeLostTrigger Initial livenodes: 
[127.0.0.1:53894_solr]
   [junit4]   2> 1062881 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 1, lastZnodeVersion -1
   [junit4]   2> 1062881 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 1
   [junit4]   2> 1062881 INFO  
(OverseerStateUpdate-99409592534564869-127.0.0.1:53897_solr-n_0000000000) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1062882 INFO  
(zkCallback-2770-thread-1-processing-n:127.0.0.1:53894_solr) 
[n:127.0.0.1:53894_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1062884 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 1062885 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 1, lastZnodeVersion 1
   [junit4]   2> 1062886 DEBUG (ScheduledTrigger-5431-thread-1) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
.auto_add_replicas with currently live nodes: 2
   [junit4]   2> 1063064 INFO  (jetty-launcher-2752-thread-2) 
[n:127.0.0.1:53894_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53894.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1063064 INFO  (jetty-launcher-2752-thread-1) 
[n:127.0.0.1:53897_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53897.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1063074 INFO  (jetty-launcher-2752-thread-2) 
[n:127.0.0.1:53894_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53894.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1063074 INFO  (jetty-launcher-2752-thread-2) 
[n:127.0.0.1:53894_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53894.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1063075 INFO  (jetty-launcher-2752-thread-1) 
[n:127.0.0.1:53897_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53897.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1063075 INFO  (jetty-launcher-2752-thread-1) 
[n:127.0.0.1:53897_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53897.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1063076 INFO  (jetty-launcher-2752-thread-2) 
[n:127.0.0.1:53894_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_C720E35CFDD4A142-001\tempDir-001\node2\.
   [junit4]   2> 1063076 INFO  (jetty-launcher-2752-thread-1) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_C720E35CFDD4A142-001\tempDir-001\node1\.
   [junit4]   2> 1063139 INFO  (zkConnectionManagerCallback-2777-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1063145 INFO  (zkConnectionManagerCallback-2781-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1063147 INFO  
(SUITE-TriggerIntegrationTest-seed#[C720E35CFDD4A142]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1063147 INFO  
(SUITE-TriggerIntegrationTest-seed#[C720E35CFDD4A142]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:53890/solr ready
   [junit4]   2> 1063164 INFO  
(TEST-TriggerIntegrationTest.testSetProperties-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testSetProperties
   [junit4]   2> 1063165 INFO  
(TEST-TriggerIntegrationTest.testSetProperties-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2
   [junit4]   2> 1063166 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 2
   [junit4]   2> 1063166 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 2
   [junit4]   2> 1063168 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 1063169 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 1063169 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 2, lastZnodeVersion 2
   [junit4]   2> 1064180 DEBUG 
(TEST-TriggerIntegrationTest.testSetProperties-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.c.a.ScheduledTriggers Changing value of autoscaling property: 
triggerScheduleDelaySeconds from: 1 to: 4
   [junit4]   2> 1068193 DEBUG 
(TEST-TriggerIntegrationTest.testSetProperties-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.c.a.ScheduledTriggers Changing value of autoscaling property: 
triggerScheduleDelaySeconds from: 4 to: 1
   [junit4]   2> 1078217 DEBUG 
(TEST-TriggerIntegrationTest.testSetProperties-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.c.a.ScheduledTriggers Changing value of autoscaling property: 
triggerCorePoolSize from: 4 to: 6
   [junit4]   2> 1088255 INFO  
(TEST-TriggerIntegrationTest.testSetProperties-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testSetProperties
   [junit4]   2> 1088291 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testNodeLostTrigger
   [junit4]   2> 1088293 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 3
   [junit4]   2> 1088294 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 3
   [junit4]   2> 1088294 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 3
   [junit4]   2> 1088294 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 1088295 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 1088296 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 3, lastZnodeVersion 3
   [junit4]   2> 1088303 INFO  (qtp18349590-12043) [n:127.0.0.1:53897_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with 
params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1088323 INFO  (qtp18349590-12043) [n:127.0.0.1:53897_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=19
   [junit4]   2> 1088336 INFO  (qtp9696091-12039) [n:127.0.0.1:53894_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> 1088340 INFO  (qtp18349590-12048) [n:127.0.0.1:53897_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> 1088340 DEBUG (qtp9696091-12034) [n:127.0.0.1:53894_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1088342 INFO  (qtp9696091-12034) [n:127.0.0.1:53894_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=12
   [junit4]   2> 1088343 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 4
   [junit4]   2> 1088344 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeLostTrigger Initial livenodes: 
[127.0.0.1:53894_solr, 127.0.0.1:53897_solr]
   [junit4]   2> 1088344 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 4
   [junit4]   2> 1088347 INFO  
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.TriggerIntegrationTest 
TestTriggerAction init
   [junit4]   2> 1088347 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 1088348 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.e.j.s.AbstractConnector Stopped 
ServerConnector@6f5be6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1088349 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.c.CoreContainer Shutting down CoreContainer instance=3075826
   [junit4]   2> 1088349 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 4, lastZnodeVersion 4
   [junit4]   2> 1088349 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1088349 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@17e9cf3: rootName = 
solr_53894, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@ce1e32
   [junit4]   2> 1088349 DEBUG (ScheduledTrigger-5431-thread-2) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
node_lost_trigger with currently live nodes: 2
   [junit4]   2> 1088357 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1088357 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@b13f98: rootName = 
solr_53894, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@a23021
   [junit4]   2> 1088363 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1088363 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@afdb7e: rootName = 
solr_53894, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@48881b
   [junit4]   2> 1088365 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:53894_solr
   [junit4]   2> 1088365 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 1088365 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1088366 INFO  
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 1088366 INFO  
(zkCallback-2770-thread-1-processing-n:127.0.0.1:53894_solr) 
[n:127.0.0.1:53894_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 1088367 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@a6e1a5{/solr,null,UNAVAILABLE}
   [junit4]   2> 1088367 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.e.j.s.session Stopped scavenging
   [junit4]   2> 1088368 INFO  (zkCallback-2780-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 1089351 DEBUG (ScheduledTrigger-5431-thread-2) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
node_lost_trigger with currently live nodes: 1
   [junit4]   2> 1089352 DEBUG (ScheduledTrigger-5431-thread-2) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeLostTrigger Tracking lost node: 
127.0.0.1:53894_solr
   [junit4]   2> 1090314 INFO  
(OverseerCollectionConfigSetProcessor-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1090354 DEBUG (ScheduledTrigger-5431-thread-2) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
node_lost_trigger with currently live nodes: 1
   [junit4]   2> 1091355 DEBUG (ScheduledTrigger-5431-thread-2) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
node_lost_trigger with currently live nodes: 1
   [junit4]   2> 1092355 DEBUG (ScheduledTrigger-5431-thread-2) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: 
node_lost_trigger with currently live nodes: 1
   [junit4]   2> 1092355 DEBUG (ScheduledTrigger-5431-thread-2) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger firing 
registered processor for lost nodes: [127.0.0.1:53894_solr]
   [junit4]   2> 1092355 DEBUG (ScheduledTrigger-5431-thread-2) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown 
inactive - processing event: {
   [junit4]   2>   "id":"201eac35cfbbeTaviin3ysdt4tlnssvb0a7hhlk",
   [junit4]   2>   "source":"node_lost_trigger",
   [junit4]   2>   "eventTime":565057765047230,
   [junit4]   2>   "eventType":"NODELOST",
   [junit4]   2>   "properties":{
   [junit4]   2>     "eventTimes":[565057765047230],
   [junit4]   2>     "nodeNames":["127.0.0.1:53894_solr"]}}
   [junit4]   2> 1092356 DEBUG (ScheduledTrigger-5431-thread-2) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: 
[node_lost_trigger]
   [junit4]   2> 1092358 INFO  (ScheduledTrigger-5431-thread-2) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.SystemLogListener Collection .system 
does not exist, disabling logging.
   [junit4]   2> 1092359 DEBUG 
(AutoscalingActionExecutor-5432-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.ScheduledTriggers -- processing actions 
for {
   [junit4]   2>   "id":"201eac35cfbbeTaviin3ysdt4tlnssvb0a7hhlk",
   [junit4]   2>   "source":"node_lost_trigger",
   [junit4]   2>   "eventTime":565057765047230,
   [junit4]   2>   "eventType":"NODELOST",
   [junit4]   2>   "properties":{
   [junit4]   2>     "eventTimes":[565057765047230],
   [junit4]   2>     "_enqueue_time_":565060770485489,
   [junit4]   2>     "nodeNames":["127.0.0.1:53894_solr"]}}
   [junit4]   2> 1092364 DEBUG 
(AutoscalingActionExecutor-5432-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.ScheduledTriggers Resuming trigger: 
node_lost_trigger after 5000ms
   [junit4]   2> 1092364 DEBUG 
(AutoscalingActionExecutor-5432-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.ScheduledTriggers -- processing took 4 
ms for event id=201eac35cfbbeTaviin3ysdt4tlnssvb0a7hhlk
   [junit4]   2> 1092372 INFO  (qtp18349590-12049) [n:127.0.0.1:53897_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=3
   [junit4]   2> 1092373 DEBUG (qtp18349590-12046) [n:127.0.0.1:53897_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1092374 INFO  (qtp18349590-12046) [n:127.0.0.1:53897_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=10
   [junit4]   2> 1092375 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 5
   [junit4]   2> 1092377 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeLostTrigger Initial livenodes: 
[127.0.0.1:53897_solr]
   [junit4]   2> 1092377 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 5
   [junit4]   2> 1092378 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 1092380 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 5, lastZnodeVersion 5
   [junit4]   2> 1094378 INFO  
(TEST-TriggerIntegrationTest.testNodeLostTrigger-seed#[C720E35CFDD4A142]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testNodeLostTrigger
   [junit4]   2> 1094403 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTriggerRestoreState
   [junit4]   2> 1094405 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode 
version 6
   [junit4]   2> 1094406 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 6
   [junit4]   2> 1094406 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 6
   [junit4]   2> 1094408 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1094409 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1094409 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1094409 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1094410 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 1094410 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@94ffa8{/solr,null,AVAILABLE}
   [junit4]   2> 1094410 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 1094411 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 6, lastZnodeVersion 6
   [junit4]   2> 1094412 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.AbstractConnector Started 
ServerConnector@be1253{HTTP/1.1,[http/1.1]}{127.0.0.1:53981}
   [junit4]   2> 1094413 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.Server Started @1102801ms
   [junit4]   2> 1094413 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=53981}
   [junit4]   2> 1094413 ERROR 
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 1094414 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ 
version 8.0.0
   [junit4]   2> 1094414 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 1094414 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1094414 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-01-25T08:47:15.082Z
   [junit4]   2> 1094420 INFO  (zkConnectionManagerCallback-2783-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1094422 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1094445 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x1612c7f6a4a0009, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1094450 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53890/solr
   [junit4]   2> 1094453 INFO  (zkConnectionManagerCallback-2787-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1094456 INFO  
(zkConnectionManagerCallback-2789-thread-1-processing-n:127.0.0.1:53981_solr) 
[n:127.0.0.1:53981_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1094464 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53981_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1094467 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53981_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1094470 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53981_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:53981_solr
   [junit4]   2> 1094471 INFO  (zkCallback-2780-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1094471 INFO  
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1094472 INFO  
(zkCallback-2788-thread-1-processing-n:127.0.0.1:53981_solr) 
[n:127.0.0.1:53981_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1094781 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53981_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53981.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1094790 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53981_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53981.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1094791 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53981_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53981.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1094792 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53981_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_C720E35CFDD4A142-001\tempDir-001\node3\.
   [junit4]   2> 1094839 INFO  (qtp18349590-12046) [n:127.0.0.1:53897_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> 1094843 INFO  (qtp13625813-12119) [n:127.0.0.1:53981_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> 1094843 DEBUG (qtp13625813-12114) [n:127.0.0.1:53981_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1094844 INFO  (qtp13625813-12114) [n:127.0.0.1:53981_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=7
   [junit4]   2> 1094844 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 7
   [junit4]   2> 1094844 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: 
[127.0.0.1:53981_solr, 127.0.0.1:53897_solr]
   [junit4]   2> 1094844 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger 
node_added_restore_trigger instantiated with properties: {event=nodeAdded, 
waitFor=5, actions=[{name=test, 
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
 enabled=true}
   [junit4]   2> 1094845 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 7
   [junit4]   2> 1094845 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1094845 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1094845 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1094847 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1094847 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@740ab7{/solr,null,AVAILABLE}
   [junit4]   2> 1094848 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.AbstractConnector Started 
ServerConnector@9cbad8{HTTP/1.1,[http/1.1]}{127.0.0.1:53996}
   [junit4]   2> 1094848 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.e.j.s.Server Started @1103236ms
   [junit4]   2> 1094848 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=53996}
   [junit4]   2> 1094848 INFO  
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.TriggerIntegrationTest 
TestTriggerAction init
   [junit4]   2> 1094848 ERROR 
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 1094848 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ 
version 8.0.0
   [junit4]   2> 1094848 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 1094848 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 1094848 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1094848 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-01-25T08:47:15.516Z
   [junit4]   2> 1094848 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 7, lastZnodeVersion 7
   [junit4]   2> 1094849 DEBUG (ScheduledTrigger-5431-thread-1) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Running 
NodeAddedTrigger node_added_restore_trigger
   [junit4]   2> 1094849 DEBUG (ScheduledTrigger-5431-thread-1) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
   [junit4]   2> 1094850 INFO  (zkConnectionManagerCallback-2793-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1094851 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1094864 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53890/solr
   [junit4]   2> 1094867 INFO  (zkConnectionManagerCallback-2797-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1094871 INFO  
(zkConnectionManagerCallback-2799-thread-1-processing-n:127.0.0.1:53996_solr) 
[n:127.0.0.1:53996_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1094881 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53996_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1094884 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53996_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1094886 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53996_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:53996_solr
   [junit4]   2> 1094887 INFO  
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1094888 INFO  (zkCallback-2780-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1094888 INFO  
(zkCallback-2788-thread-1-processing-n:127.0.0.1:53981_solr) 
[n:127.0.0.1:53981_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1094889 INFO  
(zkCallback-2798-thread-1-processing-n:127.0.0.1:53996_solr) 
[n:127.0.0.1:53996_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 1095123 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53996_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53996.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1095134 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53996_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53996.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1095134 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53996_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_53996.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1095136 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [n:127.0.0.1:53996_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_C720E35CFDD4A142-001\tempDir-001\node4\.
   [junit4]   2> 1095851 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Running 
NodeAddedTrigger node_added_restore_trigger
   [junit4]   2> 1095851 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
   [junit4]   2> 1095851 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 
127.0.0.1:53996_solr at time 565064265676559
   [junit4]   2> 1096691 INFO  (qtp18349590-12046) [n:127.0.0.1:53897_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=3
   [junit4]   2> 1096699 INFO  (qtp13625813-12119) [n:127.0.0.1:53981_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=3
   [junit4]   2> 1096710 INFO  (qtp31328484-12142) [n:127.0.0.1:53996_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> 1096711 DEBUG (qtp18349590-12048) [n:127.0.0.1:53897_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1096712 INFO  (qtp18349590-12048) [n:127.0.0.1:53897_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=28
   [junit4]   2> 1096713 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 8
   [junit4]   2> 1096715 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: 
[127.0.0.1:53981_solr, 127.0.0.1:53897_solr, 127.0.0.1:53996_solr]
   [junit4]   2> 1096715 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger 
node_added_restore_trigger instantiated with properties: {event=nodeAdded, 
waitFor=0, actions=[{name=test, 
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
 enabled=true}
   [junit4]   2> 1096715 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 8
   [junit4]   2> 1096717 INFO  
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.TriggerIntegrationTest 
TestTriggerAction init
   [junit4]   2> 1096718 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Adding node from 
marker path: 127.0.0.1:53996_solr
   [junit4]   2> 1096720 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 1096720 DEBUG (ScheduledTrigger-5431-thread-3) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Running 
NodeAddedTrigger node_added_restore_trigger
   [junit4]   2> 1096721 DEBUG (ScheduledTrigger-5431-thread-3) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
   [junit4]   2> 1096721 DEBUG (ScheduledTrigger-5431-thread-3) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger 
node_added_restore_trigger firing registered processor for nodes: 
[127.0.0.1:53996_solr] added at times [565065132174532], now=565065134717734
   [junit4]   2> 1096721 DEBUG (ScheduledTrigger-5431-thread-3) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown 
inactive - processing event: {
   [junit4]   2>   "id":"201ec7a7a6cc4Taviin3ysdt4tlnssvb0a7hhlm",
   [junit4]   2>   "source":"node_added_restore_trigger",
   [junit4]   2>   "eventTime":565065132174532,
   [junit4]   2>   "eventType":"NODEADDED",
   [junit4]   2>   "properties":{
   [junit4]   2>     "eventTimes":[565065132174532],
   [junit4]   2>     "nodeNames":["127.0.0.1:53996_solr"]}}
   [junit4]   2> 1096721 DEBUG (ScheduledTrigger-5431-thread-3) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: 
[node_added_restore_trigger]
   [junit4]   2> 1096722 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 8, lastZnodeVersion 8
   [junit4]   2> 1096723 INFO  (ScheduledTrigger-5431-thread-3) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.SystemLogListener Collection .system 
does not exist, disabling logging.
   [junit4]   2> 1096723 DEBUG 
(AutoscalingActionExecutor-5432-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.ScheduledTriggers -- processing actions 
for {
   [junit4]   2>   "id":"201ec7a7a6cc4Taviin3ysdt4tlnssvb0a7hhlm",
   [junit4]   2>   "source":"node_added_restore_trigger",
   [junit4]   2>   "eventTime":565065132174532,
   [junit4]   2>   "eventType":"NODEADDED",
   [junit4]   2>   "properties":{
   [junit4]   2>     "eventTimes":[565065132174532],
   [junit4]   2>     "_enqueue_time_":565065135182229,
   [junit4]   2>     "nodeNames":["127.0.0.1:53996_solr"]}}
   [junit4]   2> 1096724 INFO  
(TEST-TriggerIntegrationTest.testNodeAddedTriggerRestoreState-seed#[C720E35CFDD4A142])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testNodeAddedTriggerRestoreState
   [junit4]   2> 1096740 DEBUG 
(AutoscalingActionExecutor-5432-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.ScheduledTriggers Resuming trigger: 
node_added_restore_trigger after 5000ms
   [junit4]   2> 1096741 DEBUG 
(AutoscalingActionExecutor-5432-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.ScheduledTriggers -- processing took 17 
ms for event id=201ec7a7a6cc4Taviin3ysdt4tlnssvb0a7hhlm
   [junit4]   2> 1096770 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testCooldown
   [junit4]   2> 1096771 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 9
   [junit4]   2> 1096772 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 9
   [junit4]   2> 1096772 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 9
   [junit4]   2> 1096774 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 1096775 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeAdded markers
   [junit4]   2> 1096775 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 9, lastZnodeVersion 9
   [junit4]   2> 1096779 INFO  (qtp18349590-12048) [n:127.0.0.1:53897_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> 1096783 INFO  (qtp13625813-12117) [n:127.0.0.1:53981_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> 1096786 INFO  (qtp31328484-12145) [n:127.0.0.1:53996_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> 1096787 DEBUG (qtp18349590-12049) [n:127.0.0.1:53897_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1096788 INFO  (qtp18349590-12049) [n:127.0.0.1:53897_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=12
   [junit4]   2> 1096788 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 10
   [junit4]   2> 1096789 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: 
[127.0.0.1:53981_solr, 127.0.0.1:53897_solr, 127.0.0.1:53996_solr]
   [junit4]   2> 1096789 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger 
node_added_cooldown_trigger instantiated with properties: {event=nodeAdded, 
waitFor=1, actions=[{name=test, 
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
 enabled=true}
   [junit4]   2> 1096789 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 10
   [junit4]   2> 1096792 INFO  
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.TriggerIntegrationTest 
TestTriggerAction init
   [junit4]   2> 1096793 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 1096795 INFO  (qtp18349590-12049) [n:127.0.0.1:53897_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> 1096795 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 10, lastZnodeVersion 10
   [junit4]   2> 1096795 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Running 
NodeAddedTrigger node_added_cooldown_trigger
   [junit4]   2> 1096795 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
   [junit4]   2> 1096798 INFO  (qtp13625813-12119) [n:127.0.0.1:53981_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> 1096801 INFO  (qtp31328484-12147) [n:127.0.0.1:53996_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> 1096802 DEBUG (qtp18349590-12046) [n:127.0.0.1:53897_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1096803 INFO  (qtp18349590-12046) [n:127.0.0.1:53897_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=11
   [junit4]   2> 1096803 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Refreshing 
/autoscaling.json with znode version 11
   [junit4]   2> 1096805 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-22T10:27:37+13:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1096805 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: 
[127.0.0.1:53981_solr, 127.0.0.1:53897_solr, 127.0.0.1:53996_solr]
   [junit4]   2> 1096805 DEBUG 
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger 
node_added_cooldown_trigger instantiated with properties: {event=nodeAdded, 
waitFor=1, actions=[{name=test, 
class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}],
 enabled=true}
   [junit4]   2> 1096805 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Processed trigger 
updates upto znodeVersion 11
   [junit4]   2> 1096805 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1096805 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1096805 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1096806 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@c8b66b{/solr,null,AVAILABLE}
   [junit4]   2> 1096806 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread -- clean old 
nodeLost markers
   [junit4]   2> 1096806 DEBUG 
(OverseerAutoScalingTriggerThread-99409592534564869-127.0.0.1:53897_solr-n_0000000000)
 [n:127.0.0.1:53897_solr    ] o.a.s.c.a.OverseerTriggerThread Current 
znodeVersion 11, lastZnodeVersion 11
   [junit4]   2> 1096807 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@94b53e{HTTP/1.1,[http/1.1]}{127.0.0.1:54010}
   [junit4]   2> 1096807 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.e.j.s.Server Started @1105196ms
   [junit4]   2> 1096807 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=54010}
   [junit4]   2> 1096807 ERROR 
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1096807 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 1096807 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1096807 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1096807 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-01-25T08:47:17.475Z
   [junit4]   2> 1096811 INFO  (zkConnectionManagerCallback-2803-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1096812 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1096827 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53890/solr
   [junit4]   2> 1096829 INFO  (zkConnectionManagerCallback-2807-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1096833 INFO  
(zkConnectionManagerCallback-2809-thread-1-processing-n:127.0.0.1:54010_solr) 
[n:127.0.0.1:54010_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1096839 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) 
[n:127.0.0.1:54010_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 1096842 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) 
[n:127.0.0.1:54010_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1096845 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) 
[n:127.0.0.1:54010_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:54010_solr
   [junit4]   2> 1096847 INFO  
(zkCallback-2788-thread-1-processing-n:127.0.0.1:53981_solr) 
[n:127.0.0.1:53981_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1096847 INFO  
(zkCallback-2768-thread-1-processing-n:127.0.0.1:53897_solr) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1096847 INFO  
(zkCallback-2798-thread-1-processing-n:127.0.0.1:53996_solr) 
[n:127.0.0.1:53996_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1096847 INFO  (zkCallback-2780-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1096848 INFO  
(zkCallback-2808-thread-1-processing-n:127.0.0.1:54010_solr) 
[n:127.0.0.1:54010_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 1097041 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) 
[n:127.0.0.1:54010_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54010.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1097050 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) 
[n:127.0.0.1:54010_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54010.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1097051 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) 
[n:127.0.0.1:54010_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_54010.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@16fb29a
   [junit4]   2> 1097052 INFO  
(TEST-TriggerIntegrationTest.testCooldown-seed#[C720E35CFDD4A142]) 
[n:127.0.0.1:54010_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.TriggerIntegrationTest_C720E35CFDD4A142-001\tempDir-001\node5\.
   [junit4]   2> 1097797 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Running 
NodeAddedTrigger node_added_cooldown_trigger
   [junit4]   2> 1097797 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
   [junit4]   2> 1097797 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 
127.0.0.1:54010_solr at time 565066210740441
   [junit4]   2> 1098800 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Running 
NodeAddedTrigger node_added_cooldown_trigger
   [junit4]   2> 1098800 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 4
   [junit4]   2> 1098800 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger 
node_added_cooldown_trigger firing registered processor for nodes: 
[127.0.0.1:54010_solr] added at times [565066210740441], now=565067213988207
   [junit4]   2> 1098800 DEBUG (ScheduledTrigger-5431-thread-4) 
[n:127.0.0.1:53897_solr    ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown 
inactive - processing e

[...truncated too long message...]

gsFormat(name=Direct)}, docValues:{}, maxPointsInLeafNode=678, 
maxMBSortInHeap=7.5825116306554605, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1aa8fbc),
 locale=pt, timezone=Africa/Kigali
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 
(32-bit)/cpus=3,threads=1,free=300332032,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: 
[LeaderElectionIntegrationTest, SOLR749Test, TestSolrXml, 
ChangedSchemaMergeTest, SchemaVersionSpecificBehaviorTest, TestReload, 
TestJoin, TestSkipOverseerOperations, TestInitParams, QueryEqualityTest, 
TestSubQueryTransformerCrossCore, TestReloadDeadlock, ZkStateReaderTest, 
TestPostingsSolrHighlighter, PolyFieldTest, TestSimpleQParserPlugin, 
TestFieldResource, SSLMigrationTest, TestLegacyFieldCache, RequiredFieldsTest, 
SearchHandlerTest, TestSuggestSpellingConverter, TestReloadAndDeleteDocs, 
TestQuerySenderNoQuery, TestOrdValues, AutoAddReplicasPlanActionTest, 
OpenExchangeRatesOrgProviderTest, SimpleCollectionCreateDeleteTest, 
LukeRequestHandlerTest, PathHierarchyTokenizerFactoryTest, TestJsonFacets, 
CursorPagingTest, RulesTest, DeleteNodeTest, TestManagedSchemaAPI, 
TestManagedSynonymFilterFactory, TestGraphTermsQParserPlugin, 
TestConfigSetImmutable, EnumFieldTest, TestElisionMultitermQuery, 
CacheHeaderTest, SolrIndexConfigTest, TestPseudoReturnFields, 
SynonymTokenizerTest, PKIAuthenticationIntegrationTest, TestSolrFieldCacheBean, 
BadCopyFieldTest, TestLocalFSCloudBackupRestore, TestRemoteStreaming, 
TestSolrConfigHandlerCloud, HdfsRestartWhileUpdatingTest, 
TestSimDistributedQueue, SolrRequestParserTest, TestQueryUtils, 
TestGroupingSearch, TestSolrJ, XmlUpdateRequestHandlerTest, TestBinaryField, 
UnloadDistributedZkTest, CoreAdminCreateDiscoverTest, 
TestImpersonationWithHadoopAuth, TestDistribDocBasedVersion, 
TestPKIAuthenticationPlugin, FastVectorHighlighterTest, 
TestIBSimilarityFactory, SolrCoreTest, TestSimpleTextCodec, TestIndexSearcher, 
ZkCLITest, TestOnReconnectListenerSupport, TestFreeTextSuggestions, 
TestDocBasedVersionConstraints, AnalysisAfterCoreReloadTest, 
TestEmbeddedSolrServerAdminHandler, TestFiltering, SampleTest, 
TestNestedDocsSort, SuggestComponentContextFilterQueryTest, TestCollectionAPIs, 
BaseCdcrDistributedZkTest, TriLevelCompositeIdRoutingTest, 
TestScoreJoinQPScore, ClassificationUpdateProcessorFactoryTest, 
ConnectionManagerTest, BJQParserTest, TestFieldCacheSort, 
TestComputePlanAction, SolrIndexSplitterTest, InfixSuggestersTest, 
HdfsLockFactoryTest, TestXIncludeConfig, TestTolerantUpdateProcessorCloud, 
TestZkChroot, ExplicitHLLTest, TestCloudRecovery, TestNamedUpdateProcessors, 
ImplicitSnitchTest, NumericFieldsTest, UniqFieldsUpdateProcessorFactoryTest, 
TestSlowCompositeReaderWrapper, BufferStoreTest, SuggesterTSTTest, 
StatsReloadRaceTest, TestFieldCache, TestJavabinTupleStreamParser, 
HdfsChaosMonkeySafeLeaderTest, TestStressCloudBlindAtomicUpdates, 
StatelessScriptUpdateProcessorFactoryTest, TolerantUpdateProcessorTest, 
TestImplicitCoreProperties, TestClusterProperties, TestTestInjection, 
TermVectorComponentDistributedTest, TestFunctionQuery, TestChildDocTransformer, 
TestLegacyField, TestTolerantSearch, DistributedQueryElevationComponentTest, 
HdfsAutoAddReplicasIntegrationTest, TestPointFields, SortSpecParsingTest, 
PluginInfoTest, TestCryptoKeys, DateFieldTest, DirectoryFactoryTest, 
TestRealTimeGet, TestHdfsBackupRestoreCore, 
DistributedQueryComponentCustomSortTest, TestHttpShardHandlerFactory, 
HighlighterTest, TestCopyFieldCollectionResource, TestCollapseQParserPlugin, 
DistributedFacetPivotLongTailTest, TestPayloadScoreQParserPlugin, 
TestHdfsUpdateLog, MultiTermTest, ConnectionReuseTest, TestReRankQParserPlugin, 
SpellingQueryConverterTest, TestMultiWordSynonyms, TestLeaderElectionZkExpiry, 
DistribCursorPagingTest, AlternateDirectoryTest, TestSchemaResource, 
SystemLogListenerTest, TestManagedResource, TestSystemIdResolver, 
RecoveryAfterSoftCommitTest, ComputePlanActionTest, 
TestSchemalessBufferedUpdates, TestComplexPhraseQParserPlugin, 
TestRequestStatusCollectionAPI, WrapperMergePolicyFactoryTest, 
TestNumericTerms64, MetricTriggerTest, 
DistributedFacetSimpleRefinementLongTailTest, TestBlendedInfixSuggestions, 
TestSolr4Spatial2, ZkNodePropsTest, ShufflingReplicaListTransformerTest, 
RecoveryZkTest, TestRecovery, TestNodeLostTrigger, 
WordBreakSolrSpellCheckerTest, SolrCloudReportersTest, MBeansHandlerTest, 
BasicZkTest, TriggerIntegrationTest]
   [junit4] Completed [298/768 (1!)] on J0 in 214.87s, 14 tests, 1 failure <<< 
FAILURES!

[...truncated 4036 lines...]
   [junit4] Suite: 
org.apache.solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\init-core-data-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=547, maxMBSortInHeap=6.292575585075142, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5763a2),
 locale=sk, timezone=Asia/Novosibirsk
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 
(32-bit)/cpus=3,threads=1,free=17231144,total=61865984
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestICUCollationFieldOptions, TestFoldingMultitermExtrasQuery, 
TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory 
-Dtests.seed=208ABB6A55DC50C2 -Dtests.slow=true -Dtests.locale=sk 
-Dtests.timezone=Asia/Novosibirsk -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J1 | 
TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the 
following files (in the order of attempts):
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-tokenizer.bin:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-tokenizer.bin
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-ner-person.bin:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-ner-person.bin
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-sent.bin:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001\tempDir-001\collection1\conf\en-test-sent.bin
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_208ABB6A55DC50C2-001
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([208ABB6A55DC50C2]: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 2.32s, 8 tests, 1 error <<< FAILURES!

[...truncated 41481 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to