Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7215/ Java: 32bit/jdk1.8.0_144 -server -XX:+UseConcMarkSweepGC
2 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.search.stats.TestExactStatsCache Error Message: Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\collection1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-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\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\collection1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001 at __randomizedtesting.SeedInfo.seed([122C8868088CF657]:0) at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216) at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: junit.framework.TestSuite.org.apache.lucene.index.TestLongPostings 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.index.TestLongPostings_B6FA22691B178CCE-001\longpostings.199787120905034483-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestLongPostings_B6FA22691B178CCE-001\longpostings.199787120905034483-001 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestLongPostings_B6FA22691B178CCE-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestLongPostings_B6FA22691B178CCE-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.index.TestLongPostings_B6FA22691B178CCE-001\longpostings.199787120905034483-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestLongPostings_B6FA22691B178CCE-001\longpostings.199787120905034483-001 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestLongPostings_B6FA22691B178CCE-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestLongPostings_B6FA22691B178CCE-001 at __randomizedtesting.SeedInfo.seed([B6FA22691B178CCE]: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 956 lines...] [junit4] Suite: org.apache.lucene.index.TestLongPostings [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {field=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{}, maxPointsInLeafNode=34, maxMBSortInHeap=7.212237802915656, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4ad150), locale=ms-MY, timezone=Antarctica/Macquarie [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=245697096,total=356347904 [junit4] 2> NOTE: All tests run in this JVM: [TestStressIndexing, TestConjunctions, TestMultiCollector, TestUniqueTermCount, TestCharArraySet, TestFieldInfos, TestDirectMonotonic, TestPerFieldPostingsFormat, TestComplexExplanationsOfNonMatches, TestIndexWriterMaxDocs, TestAxiomaticSimilarity, TestSearchAfter, MultiCollectorTest, TestIndexWriterExceptions2, TestNearSpansOrdered, TestNRTThreads, TestMultiFields, TestBytesRef, TestSloppyMath, TestPhraseQuery, TestShardSearching, TestPerFieldPostingsFormat2, TestFilterCodecReader, TestIndexWriterDelete, TestSpanBoostQuery, TestSimpleExplanationsWithFillerDocs, TestTerm, TestIndexingSequenceNumbers, TestIndependenceChiSquared, TestNRTReaderWithThreads, FuzzyTermOnShortTermsTest, TestPerFieldDocValuesFormat, TestFastCompressionMode, TestDelegatingAnalyzerWrapper, TestSpanNotQuery, TestRollingUpdates, TestIndexWriterOutOfFileDescriptors, TestConcurrentMergeScheduler, TestPositionIncrement, TestByteArrayDataInput, TestPerSegmentDeletes, TestSentinelIntSet, TestSpanTermQuery, TestDemoParallelLeafReader, TestCharFilter, TestNoDeletionPolicy, TestTwoPhaseCommitTool, TestSimilarityBase, TestLucene60PointsFormat, TestAutomatonQueryUnicode, TestSegmentMerger, TestTermsEnum, TestSimilarity, Test2BPostings, TestSleepingLockWrapper, TestReadOnlyIndex, TestIndexWriterNRTIsCurrent, TestRegexpRandom, TestLongRangeFieldQueries, TestNoMergePolicy, TestSimpleExplanations, TestRollingBuffer, TestIndexOrDocValuesQuery, TestDocumentsWriterStallControl, TestCloseableThreadLocal, TestFieldMaskingSpanQuery, TestCharTermAttributeImpl, TestScoreCachingWrappingScorer, TestSimpleAttributeImpl, TestVirtualMethod, TestReqOptSumScorer, TestCompetitiveFreqNormAccumulator, TestSloppyPhraseQuery2, TestBasicModelG, TestCodecHoldsOpenFiles, TestWeakIdentityMap, TestForUtil, TestSortedSetSortField, TestDoubleRangeFieldQueries, TestMultiTermsEnum, TestPostingsOffsets, TestRoaringDocIdSet, TestBytesRefAttImpl, TestLongPostings] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLongPostings -Dtests.seed=B6FA22691B178CCE -Dtests.slow=true -Dtests.locale=ms-MY -Dtests.timezone=Antarctica/Macquarie -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J1 | TestLongPostings (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.index.TestLongPostings_B6FA22691B178CCE-001\longpostings.199787120905034483-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestLongPostings_B6FA22691B178CCE-001\longpostings.199787120905034483-001 [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestLongPostings_B6FA22691B178CCE-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestLongPostings_B6FA22691B178CCE-001 [junit4] > at __randomizedtesting.SeedInfo.seed([B6FA22691B178CCE]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [212/486 (1!)] on J1 in 0.96s, 2 tests, 1 error <<< FAILURES! [...truncated 12185 lines...] [junit4] Suite: org.apache.solr.search.stats.TestExactStatsCache [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\init-core-data-001 [junit4] 2> 1474188 WARN (SUITE-TestExactStatsCache-seed#[122C8868088CF657]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=39 numCloses=39 [junit4] 2> 1474188 INFO (SUITE-TestExactStatsCache-seed#[122C8868088CF657]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 1474218 INFO (SUITE-TestExactStatsCache-seed#[122C8868088CF657]-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> 1474218 INFO (SUITE-TestExactStatsCache-seed#[122C8868088CF657]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1474219 INFO (SUITE-TestExactStatsCache-seed#[122C8868088CF657]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /tl_lk/ [junit4] 2> 1474920 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\cores\collection1 [junit4] 2> 1474922 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1474923 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1474923 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1474923 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1474923 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1827d5e{/tl_lk,null,AVAILABLE} [junit4] 2> 1474925 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@d0aa4{HTTP/1.1,[http/1.1]}{127.0.0.1:49533} [junit4] 2> 1474925 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.Server Started @1482543ms [junit4] 2> 1474926 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/tl_lk, hostPort=49533, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\cores} [junit4] 2> 1474926 ERROR (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1474926 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1474926 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1474926 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1474926 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-11T08:53:21.985Z [junit4] 2> 1474928 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\solr.xml [junit4] 2> 1474937 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1474938 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1474938 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@453406, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1475132 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1475196 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1475196 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1475207 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\cores [junit4] 2> 1475207 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1475211 INFO (coreLoadExecutor-4844-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1475216 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 1475311 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1475358 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1475516 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1475549 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1, trusted=true [junit4] 2> 1475549 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1475549 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1475549 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\cores\collection1\data\] [junit4] 2> 1475552 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=56.478515625, floorSegmentMB=1.4189453125, forceMergeDeletesPctAllowed=26.71068873528499, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6774781922784532 [junit4] 2> 1475787 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1475787 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1475788 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1475788 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1475797 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=95.525390625, floorSegmentMB=2.0712890625, forceMergeDeletesPctAllowed=28.058961882398968, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15909547591445056 [junit4] 2> 1475797 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1254f19[collection1] main] [junit4] 2> 1475816 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 1475995 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1476145 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1476145 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 1476145 INFO (coreLoadExecutor-4844-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594630763398037504 [junit4] 2> 1476146 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 1476146 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 1476146 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 1476146 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 1476146 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 1476146 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 1476147 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 1476147 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 1476148 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1254f19[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1476976 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\shard0\cores\collection1 [junit4] 2> 1476979 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1476979 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1476979 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1476979 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1476980 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@16ea46a{/tl_lk,null,AVAILABLE} [junit4] 2> 1476981 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5902e8{HTTP/1.1,[http/1.1]}{127.0.0.1:49536} [junit4] 2> 1476981 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.Server Started @1484598ms [junit4] 2> 1476981 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/tl_lk, hostPort=49536, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\shard0\cores} [junit4] 2> 1476981 ERROR (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1476981 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1476981 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1476982 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1476982 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-11T08:53:24.041Z [junit4] 2> 1476983 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\shard0\solr.xml [junit4] 2> 1476995 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1476995 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1476996 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@453406, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1477178 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1477189 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1477189 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1477195 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\shard0\cores [junit4] 2> 1477195 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1477203 INFO (coreLoadExecutor-4855-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1477213 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 1477326 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1477366 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1477502 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1477518 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1, trusted=true [junit4] 2> 1477518 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1477518 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1477519 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\shard0\cores\collection1\data\] [junit4] 2> 1477522 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=56.478515625, floorSegmentMB=1.4189453125, forceMergeDeletesPctAllowed=26.71068873528499, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6774781922784532 [junit4] 2> 1477745 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1477745 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1477751 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1477751 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1477752 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=95.525390625, floorSegmentMB=2.0712890625, forceMergeDeletesPctAllowed=28.058961882398968, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15909547591445056 [junit4] 2> 1477752 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@34e204[collection1] main] [junit4] 2> 1477753 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 1477784 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1477857 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1477857 INFO (coreLoadExecutor-4855-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594630765193199616 [junit4] 2> 1477857 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 1477857 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 1477857 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 1477857 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 1477857 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 1477862 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 1477863 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 1477863 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 1477863 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 1477864 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@34e204[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1477867 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 1477880 INFO (qtp16199897-16884) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/tl_lk path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1594630765211025408)} 0 5 [junit4] 2> 1477893 INFO (qtp18039840-16904) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/tl_lk path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1594630765225705472)} 0 5 [junit4] 2> 1477897 INFO (qtp16199897-16887) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/tl_lk path=/update params={wt=javabin&version=2}{add=[0 (1594630765231996928)]} 0 3 [junit4] 2> 1477899 INFO (qtp18039840-16907) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/tl_lk path=/update params={wt=javabin&version=2}{add=[0 (1594630765236191232)]} 0 1 [junit4] 2> 1477899 INFO (qtp16199897-16889) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594630765237239808,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1477900 INFO (qtp16199897-16889) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1dbc50d commitCommandVersion:1594630765237239808 [junit4] 2> 1477927 INFO (qtp16199897-16889) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c783df[collection1] main] [junit4] 2> 1477929 INFO (qtp16199897-16889) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1477931 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c783df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1)))} [junit4] 2> 1477931 INFO (qtp16199897-16889) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/tl_lk path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 32 [junit4] 2> 1477931 INFO (qtp18039840-16909) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594630765270794240,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1477931 INFO (qtp18039840-16909) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@ffd7de commitCommandVersion:1594630765270794240 [junit4] 2> 1477947 INFO (qtp18039840-16909) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@cc9a52[collection1] main] [junit4] 2> 1477949 INFO (qtp18039840-16909) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1477951 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@cc9a52[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1)))} [junit4] 2> 1477951 INFO (qtp18039840-16909) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/tl_lk path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 19 [junit4] 2> 1477953 INFO (qtp16199897-16887) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/tl_lk path=/select params={q=a_t:one&fl=*,score&wt=javabin&version=2&debugQuery=true} hits=1 status=0 QTime=0 [junit4] 2> 1477958 INFO (qtp18039840-16910) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/tl_lk path=/select params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=*,score&shards.purpose=32768&shard.url=http://127.0.0.1:49536/tl_lk/collection1&rid=127.0.0.1-collection1-1520758405013-22&version=2&q=a_t:one&requestPurpose=GET_TERM_STATS&NOW=1520758405013&isShard=true&wt=javabin&debugQuery=false} status=0 QTime=0 [junit4] 2> 1477959 INFO (qtp18039840-16911) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/tl_lk path=/select params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&org.apache.solr.stats.termStats=a_t:on,1,1&shard.url=http://127.0.0.1:49536/tl_lk/collection1&rid=127.0.0.1-collection1-1520758405013-22&rows=10&version=2&org.apache.solr.stats.colStats=a_t,1,1,3,3&q=a_t:one&requestPurpose=GET_TOP_IDS,SET_TERM_STATS&NOW=1520758405013&isShard=true&org.apache.solr.stats.terms=a_t:on&wt=javabin&debugQuery=false} hits=1 status=0 QTime=0 [junit4] 2> 1477961 INFO (qtp18039840-16910) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/tl_lk path=/select params={df=text&distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=http://127.0.0.1:49536/tl_lk/collection1&rid=127.0.0.1-collection1-1520758405013-22&version=2&q=a_t:one&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1520758405013&ids=0&isShard=true&wt=javabin&debugQuery=true} status=0 QTime=0 [junit4] 2> 1477962 INFO (qtp18039840-16907) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/tl_lk path=/select params={q=a_t:one&shards=http://127.0.0.1:49536/tl_lk/collection1&fl=*,score&wt=javabin&version=2&debugQuery=true} rid=127.0.0.1-collection1-1520758405013-22 hits=1 status=0 QTime=7 [junit4] 1> ======================= Control Response ======================= [junit4] 1> {responseHeader={status=0,QTime=0},response={numFound=1,start=0,maxScore=0.57735026,docs=[SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Sun Mar 11 00:53:24 PST 2018, _version_=1594630765231996928, intDvoDefault=42, score=0.57735026}]},debug={rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,explain={0= [junit4] 1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.57735026 = score(freq=1.0), product of: [junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 1 = docFreq, number of documents containing term [junit4] 1> 1 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.57735026 = fieldNorm [junit4] 1> },QParser=LuceneQParser,timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}} [junit4] 1> [junit4] 1> [junit4] 1> ======================= Shard Response ======================= [junit4] 1> [junit4] 1> {responseHeader={status=0,QTime=7},response={numFound=1,start=0,maxScore=0.57735026,docs=[SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Sun Mar 11 00:53:24 PST 2018, _version_=1594630765236191232, intDvoDefault=42, score=0.57735026}]},debug={track={rid=127.0.0.1-collection1-1520758405013-22,PARSE_QUERY={http://127.0.0.1:49536/tl_lk/collection1={QTime=0,ElapsedTime=2,RequestPurpose=GET_TERM_STATS,Response={responseHeader={status=0,QTime=0},org.apache.solr.stats.terms=a_t:on,org.apache.solr.stats.termStats=a_t:on,1,1,org.apache.solr.stats.colStats=a_t,1,1,3,3,debug={timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},EXECUTE_QUERY={http://127.0.0.1:49536/tl_lk/collection1={QTime=0,ElapsedTime=1,RequestPurpose=GET_TOP_IDS,SET_TERM_STATS,NumFound=1,Response={responseHeader={status=0,QTime=0},response={numFound=1,start=0,maxScore=0.57735026,docs=[SolrDocument{id=0, score=0.57735026}]},sort_values={},debug={timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},GET_FIELDS={http://127.0.0.1:49536/tl_lk/collection1={QTime=0,ElapsedTime=1,RequestPurpose=GET_FIELDS,GET_DEBUG,NumFound=1,Response={responseHeader={status=0,QTime=0},response={numFound=1,start=0,docs=[SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Sun Mar 11 00:53:24 PST 2018, _version_=1594630765236191232, intDvoDefault=42, score=0.57735026}]},debug={rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,explain={0= [junit4] 1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.57735026 = score(freq=1.0), product of: [junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 1 = docFreq, number of documents containing term [junit4] 1> 1 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.57735026 = fieldNorm [junit4] 1> },QParser=LuceneQParser,timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}}},timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}},rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,QParser=LuceneQParser,explain={0= [junit4] 1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.57735026 = score(freq=1.0), product of: [junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 1 = docFreq, number of documents containing term [junit4] 1> 1 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.57735026 = fieldNorm [junit4] 1> }}} [junit4] 2> 1477972 INFO (qtp16199897-16889) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/tl_lk path=/update params={wt=javabin&version=2}{add=[1 (1594630765304348672)]} 0 8 [junit4] 2> 1477978 INFO (qtp18039840-16911) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/tl_lk path=/update params={wt=javabin&version=2}{add=[1 (1594630765313785856)]} 0 6 [junit4] 2> 1477979 INFO (qtp16199897-16887) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594630765321125888,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1477979 INFO (qtp16199897-16887) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1dbc50d commitCommandVersion:1594630765321125888 [junit4] 2> 1477991 INFO (qtp16199897-16887) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a21d2b[collection1] main] [junit4] 2> 1477995 INFO (searcherExecutor-4845-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a21d2b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1) Uninverting(_1(8.0.0):c1)))} [junit4] 2> 1477995 INFO (qtp16199897-16887) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1477995 INFO (qtp16199897-16887) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/tl_lk path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 16 [junit4] 2> 1477996 INFO (qtp18039840-16907) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594630765338951680,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1477997 INFO (qtp18039840-16907) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@ffd7de commitCommandVersion:1594630765338951680 [junit4] 2> 1478006 INFO (qtp18039840-16907) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@8c1930[collection1] main] [junit4] 2> 1478009 INFO (qtp18039840-16907) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1478011 INFO (searcherExecutor-4856-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8c1930[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1) Uninverting(_1(8.0.0):c1)))} [junit4] 2> 1478011 INFO (qtp18039840-16907) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/tl_lk path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 15 [junit4] 2> 1478013 INFO (qtp16199897-16889) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/tl_lk path=/select params={q=a_t:one+a_t:four&fl=*,score&wt=javabin&version=2&debugQuery=true} hits=2 status=0 QTime=0 [junit4] 2> 1478015 INFO (qtp18039840-16907) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/tl_lk path=/select params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=*,score&shards.purpose=32768&shard.url=http://127.0.0.1:49536/tl_lk/collection1&rid=127.0.0.1-collection1-1520758405073-23&version=2&q=a_t:one+a_t:four&requestPurpose=GET_TERM_STATS&NOW=1520758405073&isShard=true&wt=javabin&debugQuery=false} status=0 QTime=0 [junit4] 2> 1478016 INFO (qtp18039840-16910) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/tl_lk path=/select params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&org.apache.solr.stats.termStats=a_t:on,2,2!a_t:four,1,1&shard.url=http://127.0.0.1:49536/tl_lk/collection1&rid=127.0.0.1-collection1-1520758405073-23&rows=10&version=2&org.apache.solr.stats.colStats=a_t,2,2,8,8&q=a_t:one+a_t:four&requestPurpose=GET_TOP_IDS,SET_TERM_STATS&NOW=1520758405073&isShard=true&org.apache.solr.stats.terms=a_t:on&org.apache.solr.stats.terms=a_t:four&wt=javabin&debugQuery=false} hits=2 status=0 QTime=0 [junit4] 2> 1478017 INFO (qtp18039840-16907) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/tl_lk path=/select params={df=text&distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=http://127.0.0.1:49536/tl_lk/collection1&rid=127.0.0.1-collection1-1520758405073-23&version=2&q=a_t:one+a_t:four&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1520758405073&ids=0,1&isShard=true&wt=javabin&debugQuery=true} status=0 QTime=0 [junit4] 2> 1478018 INFO (qtp18039840-16911) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/tl_lk path=/select params={q=a_t:one+a_t:four&shards=http://127.0.0.1:49536/tl_lk/collection1&fl=*,score&wt=javabin&version=2&debugQuery=true} rid=127.0.0.1-collection1-1520758405073-23 hits=2 status=0 QTime=4 [junit4] 1> ======================= Control Response ======================= [junit4] 1> {responseHeader={status=0,QTime=0},response={numFound=2,start=0,maxScore=1.0757567,docs=[SolrDocument{id=1, range_facet_l=[1], id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1], a_t=[one two three four five], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Sun Mar 11 00:53:25 PST 2018, _version_=1594630765304348672, intDvoDefault=42, score=1.0757567}, SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Sun Mar 11 00:53:24 PST 2018, _version_=1594630765231996928, intDvoDefault=42, score=0.57735026}]},debug={rawquerystring=a_t:one a_t:four,querystring=a_t:one a_t:four,parsedquery=a_t:on a_t:four,parsedquery_toString=a_t:on a_t:four,explain={1= [junit4] 1> 1.0757567 = sum of: [junit4] 1> 0.4472136 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.4472136 = score(freq=1.0), product of: [junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 2 = docFreq, number of documents containing term [junit4] 1> 2 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.4472136 = fieldNorm [junit4] 1> 0.6285431 = weight(a_t:four in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.6285431 = score(freq=1.0), product of: [junit4] 1> 1.4054651 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 1 = docFreq, number of documents containing term [junit4] 1> 2 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.4472136 = fieldNorm [junit4] 1> ,0= [junit4] 1> 0.57735026 = sum of: [junit4] 1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.57735026 = score(freq=1.0), product of: [junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 2 = docFreq, number of documents containing term [junit4] 1> 2 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.57735026 = fieldNorm [junit4] 1> },QParser=LuceneQParser,timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}} [junit4] 1> [junit4] 1> [junit4] 1> ======================= Shard Response ======================= [junit4] 1> [junit4] 1> {responseHeader={status=0,QTime=4},response={numFound=2,start=0,maxScore=1.0757567,docs=[SolrDocument{id=1, range_facet_l=[1], id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1], a_t=[one two three four five], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Sun Mar 11 00:53:25 PST 2018, _version_=1594630765313785856, intDvoDefault=42, score=1.0757567}, SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Sun Mar 11 00:53:24 PST 2018, _version_=1594630765236191232, intDvoDefault=42, score=0.57735026}]},debug={track={rid=127.0.0.1-collection1-1520758405073-23,PARSE_QUERY={http://127.0.0.1:49536/tl_lk/collection1={QTime=0,ElapsedTime=0,RequestPurpose=GET_TERM_STATS,Response={responseHeader={status=0,QTime=0},org.apache.solr.stats.terms=a_t:on,org.apache.solr.stats.terms=a_t:four,org.apache.solr.stats.termStats=a_t:on,2,2!a_t:four,1,1,org.apache.solr.stats.colStats=a_t,2,2,8,8,debug={timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},EXECUTE_QUERY={http://127.0.0.1:49536/tl_lk/collection1={QTime=0,ElapsedTime=1,RequestPurpose=GET_TOP_IDS,SET_TERM_STATS,NumFound=2,Response={responseHeader={status=0,QTime=0},response={numFound=2,start=0,maxScore=1.0757567,docs=[SolrDocument{id=1, score=1.0757567}, SolrDocument{id=0, score=0.57735026}]},sort_values={},debug={timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},GET_FIELDS={http://127.0.0.1:49536/tl_lk/collection1={QTime=0,ElapsedTime=1,RequestPurpose=GET_FIELDS,GET_DEBUG,NumFound=2,Response={responseHeader={status=0,QTime=0},response={numFound=2,start=0,docs=[SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Sun Mar 11 00:53:24 PST 2018, _version_=1594630765236191232, intDvoDefault=42, score=0.57735026}, SolrDocument{id=1, range_facet_l=[1], id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1], a_t=[one two three four five], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Sun Mar 11 00:53:25 PST 2018, _version_=1594630765313785856, intDvoDefault=42, score=1.0757567}]},debug={rawquerystring=a_t:one a_t:four,querystring=a_t:one a_t:four,parsedquery=a_t:on a_t:four,parsedquery_toString=a_t:on a_t:four,explain={0= [junit4] 1> 0.57735026 = sum of: [junit4] 1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.57735026 = score(freq=1.0), product of: [junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 2 = docFreq, number of documents containing term [junit4] 1> 2 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.57735026 = fieldNorm [junit4] 1> ,1= [junit4] 1> 1.0757567 = sum of: [junit4] 1> 0.4472136 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.4472136 = score(freq=1.0), product of: [junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 2 = docFreq, number of documents containing term [junit4] 1> 2 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.4472136 = fieldNorm [junit4] 1> 0.6285431 = weight(a_t:four in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.6285431 = score(freq=1.0), product of: [junit4] 1> 1.4054651 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 1 = docFreq, number of documents containing term [junit4] 1> 2 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.4472136 = fieldNorm [junit4] 1> },QParser=LuceneQParser,timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}}},timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}},rawquerystring=a_t:one a_t:four,querystring=a_t:one a_t:four,parsedquery=a_t:on a_t:four,parsedquery_toString=a_t:on a_t:four,QParser=LuceneQParser,explain={1= [junit4] 1> 1.0757567 = sum of: [junit4] 1> 0.4472136 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.4472136 = score(freq=1.0), product of: [junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 2 = docFreq, number of documents containing term [junit4] 1> 2 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.4472136 = fieldNorm [junit4] 1> 0.6285431 = weight(a_t:four in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.6285431 = score(freq=1.0), product of: [junit4] 1> 1.4054651 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 1 = docFreq, number of documents containing term [junit4] 1> 2 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.4472136 = fieldNorm [junit4] 1> ,0= [junit4] 1> 0.57735026 = sum of: [junit4] 1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity], result of: [junit4] 1> 0.57735026 = score(freq=1.0), product of: [junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 from: [junit4] 1> 2 = docFreq, number of documents containing term [junit4] 1> 2 = docCount, total number of documents with field [junit4] 1> 1.0 = tf(freq=1.0), with freq of: [junit4] 1> 1.0 = freq, occurrences of term within document [junit4] 1> 0.57735026 = fieldNorm [junit4] 1> }}} [junit4] 2> 1478019 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test [junit4] 2> 1478020 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@d0aa4{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 1478021 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=3054206 [junit4] 2> 1478021 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1478021 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@a6b2b6: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@196cdba [junit4] 2> 1478026 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1478026 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1ec48c5: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@12dcb5f [junit4] 2> 1478029 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1478029 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b2c7c: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@16f8066 [junit4] 2> 1478031 INFO (coreCloseExecutor-4861-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@b30832 [junit4] 2> 1478031 INFO (coreCloseExecutor-4861-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=11733042 [junit4] 2> 1478031 INFO (coreCloseExecutor-4861-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@19962fb: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@8de3bd [junit4] 2> 1478045 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1827d5e{/tl_lk,null,UNAVAILABLE} [junit4] 2> 1478045 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 1478047 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5902e8{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 1478047 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1010741 [junit4] 2> 1478047 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1478047 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12c7b5: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1a6c63d [junit4] 2> 1478077 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1478077 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e67c5b: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@12dcb5f [junit4] 2> 1478081 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1478081 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@18b218d: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@16f8066 [junit4] 2> 1478088 INFO (coreCloseExecutor-4863-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1f94113 [junit4] 2> 1478088 INFO (coreCloseExecutor-4863-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=33112339 [junit4] 2> 1478088 INFO (coreCloseExecutor-4863-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@129d060: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@17cf588 [junit4] 2> 1478128 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@16ea46a{/tl_lk,null,UNAVAILABLE} [junit4] 2> 1478128 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 1478971 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1478973 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1478973 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1478973 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 1478973 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@701556{/tl_lk,null,AVAILABLE} [junit4] 2> 1478973 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@833184{HTTP/1.1,[http/1.1]}{127.0.0.1:49542} [junit4] 2> 1478973 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.Server Started @1486591ms [junit4] 2> 1478973 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/tl_lk, hostPort=49542, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\cores} [junit4] 2> 1478974 ERROR (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1478974 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1478974 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1478974 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1478974 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-11T08:53:26.033Z [junit4] 2> 1478975 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\solr.xml [junit4] 2> 1478987 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1478987 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1478989 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@453406, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1479178 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1479196 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1479197 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1479207 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\cores [junit4] 2> 1479207 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1479208 INFO (coreLoadExecutor-4870-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1479214 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 1479317 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1479351 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1479500 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1479534 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1, trusted=true [junit4] 2> 1479534 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1479534 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1479534 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\cores\collection1\data\] [junit4] 2> 1479539 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=56.478515625, floorSegmentMB=1.4189453125, forceMergeDeletesPctAllowed=26.71068873528499, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6774781922784532 [junit4] 2> 1479755 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1479755 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1479756 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1479756 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1479758 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=95.525390625, floorSegmentMB=2.0712890625, forceMergeDeletesPctAllowed=28.058961882398968, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15909547591445056 [junit4] 2> 1479758 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ae8139[collection1] main] [junit4] 2> 1479760 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 1479763 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1479815 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1479815 INFO (searcherExecutor-4871-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 1479815 INFO (coreLoadExecutor-4870-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594630767246311424 [junit4] 2> 1479816 INFO (searcherExecutor-4871-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 1479816 INFO (searcherExecutor-4871-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 1479816 INFO (searcherExecutor-4871-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 1479816 INFO (searcherExecutor-4871-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 1479816 INFO (searcherExecutor-4871-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 1479816 INFO (searcherExecutor-4871-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 1479816 INFO (searcherExecutor-4871-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 1479817 INFO (searcherExecutor-4871-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 1479818 INFO (searcherExecutor-4871-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ae8139[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1481006 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 1481006 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1481006 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1481006 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 1481006 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1afe5d8{/tl_lk,null,AVAILABLE} [junit4] 2> 1481009 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@82504c{HTTP/1.1,[http/1.1]}{127.0.0.1:49545} [junit4] 2> 1481009 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.Server Started @1488626ms [junit4] 2> 1481009 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/tl_lk, hostPort=49545, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\shard0\cores} [junit4] 2> 1481009 ERROR (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1481009 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1481009 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1481009 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1481009 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-11T08:53:28.068Z [junit4] 2> 1481011 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\shard0\solr.xml [junit4] 2> 1481024 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1481024 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1481027 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@453406, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1481210 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1481222 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1481223 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1481227 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\shard0\cores [junit4] 2> 1481227 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1481272 INFO (coreLoadExecutor-4881-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1481274 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 1481407 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1481434 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1481603 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1481625 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1, trusted=true [junit4] 2> 1481626 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@453406 [junit4] 2> 1481626 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1481626 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\shard0\cores\collection1\data\] [junit4] 2> 1481632 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=56.478515625, floorSegmentMB=1.4189453125, forceMergeDeletesPctAllowed=26.71068873528499, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6774781922784532 [junit4] 2> 1481869 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1481869 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1481872 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1481872 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1481874 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=95.525390625, floorSegmentMB=2.0712890625, forceMergeDeletesPctAllowed=28.058961882398968, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15909547591445056 [junit4] 2> 1481876 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@181db8a[collection1] main] [junit4] 2> 1481879 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 1481885 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1481939 INFO (coreLoadExecutor-4881-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1481939 INFO (searcherExecutor-4882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 1481939 INFO (searcherExecutor-4882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 1481941 INFO (searcherExecutor-4882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 1481941 INFO (searcherExecutor-4882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 1481941 INFO (searcherExecutor-4882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 1481941 INFO (searcherExecutor-4882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 1481941 INFO (searcherExecutor-4882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 1481942 INFO (searcherExecutor-4882-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell i [...truncated too long message...] ST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5adb4e: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@12dcb5f [junit4] 2> 1497062 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1497062 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@fcf211: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@16f8066 [junit4] 2> 1497065 INFO (coreCloseExecutor-4954-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1d4ee45 [junit4] 2> 1497065 INFO (coreCloseExecutor-4954-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=30731845 [junit4] 2> 1497066 INFO (coreCloseExecutor-4954-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@967b37: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@11056e [junit4] 2> 1497093 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1ecee5d{/tl_lk,null,UNAVAILABLE} [junit4] 2> 1497094 INFO (TEST-TestExactStatsCache.test-seed#[122C8868088CF657]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 1497106 INFO (SUITE-TestExactStatsCache-seed#[122C8868088CF657]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=5, maxDocsPerChunk=8, blockSize=8), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=5, blockSize=8)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@d435d8), locale=vi, timezone=Pacific/Pitcairn [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=269355632,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [RegexBoostProcessorTest, TestReqParamsAPI, TestJavabinTupleStreamParser, ReplaceNodeTest, SortByFunctionTest, TestFieldCacheVsDocValues, TestRawResponseWriter, TestStressRecovery, TestSchemaVersionResource, TestSkipOverseerOperations, SolrMetricsIntegrationTest, CustomCollectionTest, CurrencyRangeFacetCloudTest, TestDocBasedVersionConstraints, ScheduledMaintenanceTriggerTest, TestConfigSets, TestSmileRequest, InfixSuggestersTest, SolrGraphiteReporterTest, EnumFieldTest, BadCopyFieldTest, TestExclusionRuleCollectionAccess, TestPKIAuthenticationPlugin, LargeFieldTest, TestLegacyField, TestCodecSupport, TestSubQueryTransformerCrossCore, TestTriggerIntegration, OverriddenZkACLAndCredentialsProvidersTest, TestQueryWrapperFilter, BlockJoinFacetRandomTest, V2StandaloneTest, TestNonDefinedSimilarityFactory, TestScoreJoinQPScore, TestDistribStateManager, BasicAuthIntegrationTest, ScriptEngineTest, CollectionStateFormat2Test, BadIndexSchemaTest, TestCollectionAPIs, ZkFailoverTest, TestExtendedDismaxParser, CloudMLTQParserTest, CdcrBidirectionalTest, TestEmbeddedSolrServerSchemaAPI, TestCloudDeleteByQuery, HdfsUnloadDistributedZkTest, TestRTGBase, UUIDFieldTest, TestZkAclsWithHadoopAuth, TestOrdValues, TestWordDelimiterFilterFactory, TestShortCircuitedRequests, TestSimpleTrackingShardHandler, MoveReplicaHDFSFailoverTest, TestSolrCoreParser, RegexBytesRefFilterTest, TestEmbeddedSolrServerAdminHandler, ClusterStateUpdateTest, ConcurrentCreateRoutedAliasTest, TestRandomFaceting, DirectoryFactoryTest, BigEndianAscendingWordSerializerTest, TestConfigSetsAPI, PrimitiveFieldTypeTest, DistributedIntervalFacetingTest, SearchHandlerTest, TestBackupRepositoryFactory, PKIAuthenticationIntegrationTest, GraphQueryTest, ResponseLogComponentTest, TestLMDirichletSimilarityFactory, TestComplexPhraseLeadingWildcard, TestRandomDVFaceting, CloudExitableDirectoryReaderTest, DistributedFacetPivotLongTailTest, TestTestInjection, TestRetrieveFieldsOptimizer, TestNestedDocsSort, DeleteLastCustomShardedReplicaTest, VersionInfoTest, TestMaxTokenLenTokenizer, TestTolerantUpdateProcessorCloud, FieldAnalysisRequestHandlerTest, TestSolrConfigHandler, RankQueryTest, PreAnalyzedFieldManagedSchemaCloudTest, LoggingHandlerTest, TestSolrDeletionPolicy2, HdfsRestartWhileUpdatingTest, TestMissingGroups, OverseerTest, AssignTest, ConnectionReuseTest, TestComponentsName, TestBlendedInfixSuggestions, TestStressLiveNodes, TestDistributedStatsComponentCardinality, TestRecoveryHdfs, AddBlockUpdateTest, TestDistributedSearch, SyncSliceTest, TestFuzzyAnalyzedSuggestions, StressHdfsTest, TestLMJelinekMercerSimilarityFactory, FullSolrCloudDistribCmdsTest, ZkSolrClientTest, TestDocSet, TestMultiValuedNumericRangeQuery, NoCacheHeaderTest, TestCloudJSONFacetJoinDomain, RestartWhileUpdatingTest, MinimalSchemaTest, DebugComponentTest, TestAddFieldRealTimeGet, TestWriterPerf, SpellCheckCollatorWithCollapseTest, TestSolrQueryParser, TestManagedSchemaAPI, ClassificationUpdateProcessorFactoryTest, TestSchemaResource, LIRRollingUpdatesTest, TestCloudSchemaless, PreAnalyzedFieldTest, SolrMetricManagerTest, DistributedTermsComponentTest, TestHdfsBackupRestoreCore, TestSlowCompositeReaderWrapper, HdfsBasicDistributedZkTest, TestCopyFieldCollectionResource, StatsReloadRaceTest, CdcrReplicationDistributedZkTest, DisMaxRequestHandlerTest, CSVRequestHandlerTest, TestStressLucene, TestFastLRUCache, TestJsonFacetsWithNestedObjects, TestUtilizeNode, TestManagedResource, FileBasedSpellCheckerTest, TestRandomFlRTGCloud, TestSha256AuthenticationProvider, CollectionTooManyReplicasTest, TestNodeAddedTrigger, ChaosMonkeyNothingIsSafeWithPullReplicasTest, DistributedFacetExistsSmallTest, TestClassicSimilarityFactory, ShardRoutingTest, NodeAddedTriggerTest, SolrJmxReporterTest, TestDistribIDF, TestPHPSerializedResponseWriter, CdcrUpdateLogTest, ZkStateReaderTest, BasicFunctionalityTest, TestExactStatsCache] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestExactStatsCache -Dtests.seed=122C8868088CF657 -Dtests.slow=true -Dtests.locale=vi -Dtests.timezone=Pacific/Pitcairn -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] ERROR 0.00s J1 | TestExactStatsCache (suite) <<< [junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts): [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control\collection1 [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001\control [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.stats.TestExactStatsCache_122C8868088CF657-001\tempDir-001 [junit4] > at __randomizedtesting.SeedInfo.seed([122C8868088CF657]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [303/785 (1!)] on J1 in 25.85s, 1 test, 1 error <<< FAILURES! [...truncated 46802 lines...] [repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7215/consoleText [repro] Revision: 8f1350af20c682df7b7fb9e3af762e03b944fdb9 [repro] Ant options: "-Dargs=-server -XX:+UseConcMarkSweepGC" [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr\core [repro] TestExactStatsCache [repro] lucene\core [repro] TestLongPostings [repro] ant compile-test [...truncated 3313 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestExactStatsCache" -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseConcMarkSweepGC" -Dtests.seed=122C8868088CF657 -Dtests.slow=true -Dtests.locale=vi -Dtests.timezone=Pacific/Pitcairn -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [...truncated 69 lines...] [repro] ant compile-test [...truncated 88 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestLongPostings" -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseConcMarkSweepGC" -Dtests.seed=B6FA22691B178CCE -Dtests.slow=true -Dtests.locale=ms-MY -Dtests.timezone=Antarctica/Macquarie -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [...truncated 74 lines...] [repro] Failures: [repro] 0/5 failed: org.apache.lucene.index.TestLongPostings [repro] 0/5 failed: org.apache.solr.search.stats.TestExactStatsCache [repro] Exiting with code 0 [...truncated 75 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org