lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-7.2-Windows (32bit/jdk1.8.0_144) - Build # 25 - Still Unstable!
Date Wed, 20 Dec 2017 10:11:54 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.2-Windows/25/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseParallelGC

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

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

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

	at __randomizedtesting.SeedInfo.seed([174B936D7F1638F4]: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.store.TestSimpleFSLockFactory

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

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

	at __randomizedtesting.SeedInfo.seed([174B936D7F1638F4]:0)
	at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
	at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
	at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at java.lang.Thread.run(Thread.java:748)


FAILED:  junit.framework.TestSuite.org.apache.solr.search.TestPseudoReturnFields

Error Message:
Could not remove the following files (in the order of attempts):    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog\tlog.0000000000000000000: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog\tlog.0000000000000000000    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
   C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog
   C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001
   C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog\tlog.0000000000000000000: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog\tlog.0000000000000000000
   C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001

	at __randomizedtesting.SeedInfo.seed([BD018FE78F815C8E]: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 884 lines...]
   [junit4] Suite: org.apache.lucene.store.TestSimpleFSLockFactory
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {content=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{}, maxPointsInLeafNode=717, maxMBSortInHeap=7.802999260098814, sim=RandomSimilarity(queryNorm=true): {content=DFR I(n)B2}, locale=et-EE, timezone=Europe/London
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=193312400,total=293076992
   [junit4]   2> NOTE: All tests run in this JVM: [TestSpanNotQuery, TestBM25Similarity, TestFlex, TestBasics, TestUsageTrackingFilterCachingPolicy, TestPerFieldPostingsFormat2, TestEarlyTerminatingSortingCollector, TestBoolean2, TestTermVectorsReader, TestTermsEnum, TestSortRandom, TestDelegatingAnalyzerWrapper, TestFixedLengthBytesRefArray, TestDateTools, TestAllFilesHaveChecksumFooter, TestGeoUtils, TestPayloads, TestCustomTermFreq, TestIndexWriterLockRelease, TestBytesRefArray, TestDirectMonotonic, TestCharTermAttributeImpl, TestQueryBuilder, TestLongValuesSource, TestPagedBytes, TestSearchAfter, TestDemo, TestNIOFSDirectory, TestIndexWriterNRTIsCurrent, TestReadOnlyIndex, TestBlockPostingsFormat, TestRateLimiter, TestBlendedTermQuery, TestMultiTermsEnum, Test2BBKDPoints, TestBooleanScorer, TestSpanMultiTermQueryWrapper, TestReaderClosed, TestIndexTooManyDocs, TestNRTCachingDirectory, TestShardSearching, TestDocumentWriter, TestTermVectorsWriter, TestAutomatonQuery, TestIntroSelector, TestPositiveScoresOnlyCollector, TestSimilarityBase, TestMultiLevelSkipList, TestLongPostings, TestGraphTokenStreamFiniteStrings, TestSegmentTermDocs, TestTransactions, TestFuzzyQuery, TestFieldValueQuery, TestNoMergeScheduler, TestPointQueries, TestNRTThreads, TestStressDeletes, TestForTooMuchCloning, TestNGramPhraseQuery, TestBooleanRewrites, TestPrefixCodedTerms, TestIndexWriterOutOfFileDescriptors, TestIndexWriterDelete, TestPerFieldPostingsFormat, TestSpanFirstQuery, TestSizeBoundedForceMerge, TestLucene50StoredFieldsFormatHighCompression, TestDocIdsWriter, TestSimpleFSLockFactory]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSimpleFSLockFactory -Dtests.seed=174B936D7F1638F4 -Dtests.slow=true -Dtests.locale=et-EE -Dtests.timezone=Europe/London -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s J1 | TestSimpleFSLockFactory (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSLockFactory_174B936D7F1638F4-001\tempDir-007: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSLockFactory_174B936D7F1638F4-001\tempDir-007
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSLockFactory_174B936D7F1638F4-001\tempDir-007\extra0: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSLockFactory_174B936D7F1638F4-001\tempDir-007\extra0
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSLockFactory_174B936D7F1638F4-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J1\temp\lucene.store.TestSimpleFSLockFactory_174B936D7F1638F4-001
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([174B936D7F1638F4]:0)
   [junit4]    > 	at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [200/458 (1!)] on J1 in 7.25s, 7 tests, 1 error <<< FAILURES!

[...truncated 360 lines...]
   [junit4] Suite: org.apache.lucene.store.TestFileSwitchDirectory
   [junit4] IGNOR/A 0.01s J1 | TestFileSwitchDirectory.testFsyncDoesntCreateNewFiles
   [junit4]    > Assumption #1: test only works for FSDirectory subclasses
   [junit4] IGNOR/A 0.01s J1 | TestFileSwitchDirectory.testPendingDeletions
   [junit4]    > Assumption #1: we can only install VirusCheckingFS on an FSDirectory
   [junit4]   2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=false): {}, locale=ar-LY, timezone=America/Guadeloupe
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=54058544,total=265027584
   [junit4]   2> NOTE: All tests run in this JVM: [TestSpanNotQuery, TestBM25Similarity, TestFlex, TestBasics, TestUsageTrackingFilterCachingPolicy, TestPerFieldPostingsFormat2, TestEarlyTerminatingSortingCollector, TestBoolean2, TestTermVectorsReader, TestTermsEnum, TestSortRandom, TestDelegatingAnalyzerWrapper, TestFixedLengthBytesRefArray, TestDateTools, TestAllFilesHaveChecksumFooter, TestGeoUtils, TestPayloads, TestCustomTermFreq, TestIndexWriterLockRelease, TestBytesRefArray, TestDirectMonotonic, TestCharTermAttributeImpl, TestQueryBuilder, TestLongValuesSource, TestPagedBytes, TestSearchAfter, TestDemo, TestNIOFSDirectory, TestIndexWriterNRTIsCurrent, TestReadOnlyIndex, TestBlockPostingsFormat, TestRateLimiter, TestBlendedTermQuery, TestMultiTermsEnum, Test2BBKDPoints, TestBooleanScorer, TestSpanMultiTermQueryWrapper, TestReaderClosed, TestIndexTooManyDocs, TestNRTCachingDirectory, TestShardSearching, TestDocumentWriter, TestTermVectorsWriter, TestAutomatonQuery, TestIntroSelector, TestPositiveScoresOnlyCollector, TestSimilarityBase, TestMultiLevelSkipList, TestLongPostings, TestGraphTokenStreamFiniteStrings, TestSegmentTermDocs, TestTransactions, TestFuzzyQuery, TestFieldValueQuery, TestNoMergeScheduler, TestPointQueries, TestNRTThreads, TestStressDeletes, TestForTooMuchCloning, TestNGramPhraseQuery, TestBooleanRewrites, TestPrefixCodedTerms, TestIndexWriterOutOfFileDescriptors, TestIndexWriterDelete, TestPerFieldPostingsFormat, TestSpanFirstQuery, TestSizeBoundedForceMerge, TestLucene50StoredFieldsFormatHighCompression, TestDocIdsWriter, TestSimpleFSLockFactory, TestDirectoryReaderReopen, TestConcurrentMergeScheduler, TestMultiFields, TestCharsRef, TestNRTReaderWithThreads, TestMaxTermFrequency, TestMinShouldMatch2, TestLSBRadixSorter, TestReqExclBulkScorer, TestStressIndexing, TestNumericUtils, TestDirectory, TestMultiTermConstantScore, TestTermdocPerf, TestTermVectors, TestTermRangeQuery, TestIndexWriterForceMerge, TestIndexReaderClose, TestMultiPhraseEnum, TestRollingBuffer, TestSentinelIntSet, TestTransactionRollback, TestParallelTermEnum, TestIndexWriterMaxDocs, TestLucene70NormsFormat, TestDoubleRangeFieldQueries, TestDocValuesQueries, TestStopFilter, TestTotalHitCountCollector, TestSpanTermQuery, TestIndexWriterExceptions2, TestCompiledAutomaton, TestPolygon2D, TestExitableDirectoryReader, TestIndexWriterOnJRECrash, TestNot, TestLucene60FieldInfoFormat, TestCustomSearcherSort, TestIndexWriterReader, Test2BPostings, TestBytesRefHash, TestUpgradeIndexMergePolicy, TestDeterminism, TestPrefixInBooleanQuery, TestFastDecompressionMode, TestMultiThreadTermVectors, TestSimpleSearchEquivalence, TestDeterminizeLexicon, TestIndexWriterOnDiskFull, TestMixedCodecs, TestSpanExplanations, TestFastCompressionMode, TestStressIndexing2, TestLazyProxSkipping, TestMergeSchedulerExternal, TestIndexInput, TestDisjunctionMaxQuery, TestNearSpansOrdered, TestSameScoresWithThreads, TestLiveFieldValues, TestSpanSearchEquivalence, TestDoc, TestNoDeletionPolicy, TestPerSegmentDeletes, TestUniqueTermCount, TestAllFilesHaveCodecHeader, TestTermScorer, TestFileSwitchDirectory]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestFileSwitchDirectory -Dtests.seed=174B936D7F1638F4 -Dtests.slow=true -Dtests.locale=ar-LY -Dtests.timezone=America/Guadeloupe -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s J1 | TestFileSwitchDirectory (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J1\temp\lucene.store.TestFileSwitchDirectory_174B936D7F1638F4-001\foo-008: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\lucene\build\core\test\J1\temp\lucene.store.TestFileSwitchDirectory_174B936D7F1638F4-001\foo-008
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([174B936D7F1638F4]:0)
   [junit4]    > 	at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [313/458 (2!)] on J1 in 1.83s, 43 tests, 1 error, 2 skipped <<< FAILURES!

[...truncated 11105 lines...]
   [junit4] Suite: org.apache.solr.search.TestPseudoReturnFields
   [junit4]   2> 1037597 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001
   [junit4]   2> 1037598 WARN  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
   [junit4]   2> 1037598 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1037604 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1037606 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1037607 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1037659 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 1037675 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1037682 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.s.IndexSchema Loaded schema test/1.4 with uniqueid field id
   [junit4]   2> 1037771 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1891979
   [junit4]   2> 1037781 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1891979
   [junit4]   2> 1037781 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1891979
   [junit4]   2> 1037783 INFO  (coreLoadExecutor-3601-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1037784 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.2-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1037845 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
   [junit4]   2> 1037856 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1037863 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.4 with uniqueid field id
   [junit4]   2> 1037865 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1, trusted=true
   [junit4]   2> 1037865 INFO  (coreLoadExecutor-3601-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@1891979
   [junit4]   2> 1037866 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1037866 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\]
   [junit4]   2> 1037868 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7333941606540909]
   [junit4]   2> 1037871 WARN  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 1037956 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1037956 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1037957 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1037957 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1037958 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=36.5146484375, floorSegmentMB=1.2392578125, forceMergeDeletesPctAllowed=6.590052646463342, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4227531346210215
   [junit4]   2> 1037958 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d2b55[collection1] main]
   [junit4]   2> 1037959 WARN  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
   [junit4]   2> 1037959 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1037960 INFO  (coreLoadExecutor-3601-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1587293395059998720
   [junit4]   2> 1037962 INFO  (searcherExecutor-3602-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3d2b55[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1037962 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1037966 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[42 (1587293395063144448)]} 0 3
   [junit4]   2> 1037972 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[43 (1587293395071533056)]} 0 0
   [junit4]   2> 1037973 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[44 (1587293395073630208)]} 0 0
   [junit4]   2> 1037974 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[45 (1587293395074678784)]} 0 0
   [junit4]   2> 1037975 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[46 (1587293395074678785)]} 0 0
   [junit4]   2> 1037975 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1587293395075727360,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1037975 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@23a96f commitCommandVersion:1587293395075727360
   [junit4]   2> 1037982 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@af1a69[collection1] main]
   [junit4]   2> 1037983 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1037989 INFO  (searcherExecutor-3602-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@af1a69[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):C5)))}
   [junit4]   2> 1037989 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 13
   [junit4]   2> 1037991 INFO  (TEST-TestPseudoReturnFields.testScoreAndAllRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testScoreAndAllRealFields
   [junit4]   2> 1037993 INFO  (TEST-TestPseudoReturnFields.testScoreAndAllRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395093553152)]} 0 2
   [junit4]   2> 1037994 INFO  (TEST-TestPseudoReturnFields.testScoreAndAllRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=score,*&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1037996 INFO  (TEST-TestPseudoReturnFields.testScoreAndAllRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*,score&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1037996 INFO  (TEST-TestPseudoReturnFields.testScoreAndAllRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testScoreAndAllRealFields
   [junit4]   2> 1037999 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testGlobsAndScore
   [junit4]   2> 1038000 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395101941760)]} 0 0
   [junit4]   2> 1038001 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&indent=true&fl=val_*,score&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038002 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*,subj*,score&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038002 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*&fl=score&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038004 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*,score&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038005 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testGlobsAndScore
   [junit4]   2> 1038007 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testAugmentersGlobsExplicitAndScoreOhMy
   [junit4]   2> 1038009 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395109281792)]} 0 0
   [junit4]   2> 1038010 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=id,subj*,val_*,[explain],score,[docid]} hits=5 status=0 QTime=0
   [junit4]   2> 1038015 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=id&fl=subj*&fl=val_*&fl=[explain]&fl=score&fl=[docid]} hits=5 status=0 QTime=0
   [junit4]   2> 1038016 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=[docid],subj*,id,val_*,score,[explain]} hits=5 status=0 QTime=0
   [junit4]   2> 1038019 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=[docid]&fl=subj*&fl=id&fl=val_*&fl=score&fl=[explain]} hits=5 status=0 QTime=0
   [junit4]   2> 1038020 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=id,subj*,score,[explain],val_*,[docid]} hits=5 status=0 QTime=0
   [junit4]   2> 1038021 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=id&fl=subj*&fl=score&fl=[explain]&fl=val_*&fl=[docid]} hits=5 status=0 QTime=0
   [junit4]   2> 1038022 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=id,subj*,val_*,[explain],[docid],score} hits=5 status=0 QTime=0
   [junit4]   2> 1038024 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=id&fl=subj*&fl=val_*&fl=[explain]&fl=[docid]&fl=score} hits=5 status=0 QTime=0
   [junit4]   2> 1038033 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=[docid],[explain],id,val_*,subj*,score} hits=5 status=0 QTime=0
   [junit4]   2> 1038035 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=[docid]&fl=[explain]&fl=id&fl=val_*&fl=subj*&fl=score} hits=5 status=0 QTime=0
   [junit4]   2> 1038036 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=[docid],subj*,score,val_*,id,[explain]} hits=5 status=0 QTime=0
   [junit4]   2> 1038038 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=[docid]&fl=subj*&fl=score&fl=val_*&fl=id&fl=[explain]} hits=5 status=0 QTime=0
   [junit4]   2> 1038040 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=[explain],id,[docid],score,subj*,val_*} hits=5 status=0 QTime=0
   [junit4]   2> 1038042 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=[explain]&fl=id&fl=[docid]&fl=score&fl=subj*&fl=val_*} hits=5 status=0 QTime=0
   [junit4]   2> 1038044 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=val_*,[docid],id,subj*,[explain],score} hits=5 status=0 QTime=0
   [junit4]   2> 1038047 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=val_*&fl=[docid]&fl=id&fl=subj*&fl=[explain]&fl=score} hits=5 status=0 QTime=0
   [junit4]   2> 1038048 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=val_*,[explain],subj*,score,id,[docid]} hits=5 status=0 QTime=0
   [junit4]   2> 1038049 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=val_*&fl=[explain]&fl=subj*&fl=score&fl=id&fl=[docid]} hits=5 status=0 QTime=0
   [junit4]   2> 1038051 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=id,[docid],subj*,[explain],score,val_*} hits=5 status=0 QTime=0
   [junit4]   2> 1038053 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=id&fl=[docid]&fl=subj*&fl=[explain]&fl=score&fl=val_*} hits=5 status=0 QTime=0
   [junit4]   2> 1038054 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=subj*,[explain],[docid],score,id,val_*} hits=5 status=0 QTime=0
   [junit4]   2> 1038056 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=subj*&fl=[explain]&fl=[docid]&fl=score&fl=id&fl=val_*} hits=5 status=0 QTime=0
   [junit4]   2> 1038057 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=id,[docid],val_*,[explain],score,subj*} hits=5 status=0 QTime=0
   [junit4]   2> 1038059 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=id&fl=[docid]&fl=val_*&fl=[explain]&fl=score&fl=subj*} hits=5 status=0 QTime=0
   [junit4]   2> 1038061 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMy-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testAugmentersGlobsExplicitAndScoreOhMy
   [junit4]   2> 1038064 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testAugmentersAndScore
   [junit4]   2> 1038065 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395170099200)]} 0 0
   [junit4]   2> 1038067 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=[docid],x_alias:[value+v%3D10+t%3Dint],score&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038068 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],x_alias:[value+v%3D10+t%3Dint],[explain],score&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038070 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid]&fl=x_alias:[value+v%3D10+t%3Dint],[explain]&fl=score&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038071 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid]&fl=x_alias:[value+v%3D10+t%3Dint]&fl=[explain]&fl=score&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038074 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndScore-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testAugmentersAndScore
   [junit4]   2> 1038076 INFO  (TEST-TestPseudoReturnFields.testAllRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testAllRealFieldsRTG
   [junit4]   2> 1038077 INFO  (TEST-TestPseudoReturnFields.testAllRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395182682112)]} 0 0
   [junit4]   2> 1038078 INFO  (TEST-TestPseudoReturnFields.testAllRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038079 INFO  (TEST-TestPseudoReturnFields.testAllRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038080 INFO  (TEST-TestPseudoReturnFields.testAllRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=*&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038081 INFO  (TEST-TestPseudoReturnFields.testAllRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=*&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038082 INFO  (TEST-TestPseudoReturnFields.testAllRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testAllRealFieldsRTG
   [junit4]   2> 1038085 INFO  (TEST-TestPseudoReturnFields.testAllRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testAllRealFields
   [junit4]   2> 1038086 INFO  (TEST-TestPseudoReturnFields.testAllRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395191070720)]} 0 0
   [junit4]   2> 1038087 INFO  (TEST-TestPseudoReturnFields.testAllRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038089 INFO  (TEST-TestPseudoReturnFields.testAllRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=*&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038090 INFO  (TEST-TestPseudoReturnFields.testAllRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testAllRealFields
   [junit4]   2> 1038092 INFO  (TEST-TestPseudoReturnFields.testGlobs-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testGlobs
   [junit4]   2> 1038094 INFO  (TEST-TestPseudoReturnFields.testGlobs-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395200507904)]} 0 0
   [junit4]   2> 1038094 INFO  (TEST-TestPseudoReturnFields.testGlobs-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=val_*&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038095 INFO  (TEST-TestPseudoReturnFields.testGlobs-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=val_*,subj*,ss*} hits=5 status=0 QTime=0
   [junit4]   2> 1038096 INFO  (TEST-TestPseudoReturnFields.testGlobs-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=val_*&fl=subj*,ss*} hits=5 status=0 QTime=0
   [junit4]   2> 1038097 INFO  (TEST-TestPseudoReturnFields.testGlobs-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&rows=1&fl=val_*&fl=subj*&fl=ss*} hits=5 status=0 QTime=0
   [junit4]   2> 1038098 INFO  (TEST-TestPseudoReturnFields.testGlobs-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testGlobs
   [junit4]   2> 1038101 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testGlobsAndScoreRTG
   [junit4]   2> 1038102 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395208896512)]} 0 1
   [junit4]   2> 1038104 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=val_*,score&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038105 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*,subj*,score&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038106 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*&fl=score&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038107 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*,score&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038109 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=val_*,score&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038109 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*,subj*,score&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038110 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*&fl=score&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038111 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*,score&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038112 INFO  (TEST-TestPseudoReturnFields.testGlobsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testGlobsAndScoreRTG
   [junit4]   2> 1038114 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testAugmentersAndExplicit
   [junit4]   2> 1038116 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395221479424)]} 0 0
   [junit4]   2> 1038117 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id,[docid],[explain],x_alias:[value+v%3D10+t%3Dint]&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038118 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id&fl=[docid],[explain],x_alias:[value+v%3D10+t%3Dint]&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038119 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id&fl=[docid]&fl=[explain]&fl=x_alias:[value+v%3D10+t%3Dint]&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038120 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testAugmentersAndExplicit
   [junit4]   2> 1038123 INFO  (TEST-TestPseudoReturnFields.testMultiValued-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultiValued
   [junit4]   2> 1038124 INFO  (TEST-TestPseudoReturnFields.testMultiValued-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395230916608)]} 0 0
   [junit4]   2> 1038125 INFO  (TEST-TestPseudoReturnFields.testMultiValued-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=id,score&fq=id:42&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1038126 INFO  (TEST-TestPseudoReturnFields.testMultiValued-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:42&fl=val_ss:val_i,+val2_ss:10&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1038127 INFO  (TEST-TestPseudoReturnFields.testMultiValued-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=val_ss:val_i,+val2_ss:10&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038127 INFO  (TEST-TestPseudoReturnFields.testMultiValued-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultiValued
   [junit4]   2> 1038130 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testAugmentersRTG
   [junit4]   2> 1038131 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395238256640)]} 0 0
   [junit4]   2> 1038136 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],[shard],[explain],x_alias:[value+v%3D10+t%3Dint],abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=5
   [junit4]   2> 1038137 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],[shard],abs(val_i)&fl=[explain],x_alias:[value+v%3D10+t%3Dint]&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038140 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],[shard]&fl=[explain],x_alias:[value+v%3D10+t%3Dint]&fl=abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038141 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid]&fl=[shard]&fl=[explain]&fl=x_alias:[value+v%3D10+t%3Dint]&fl=abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038147 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9329ac[collection1] realtime]
   [junit4]   2> 1038148 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],[shard],[explain],x_alias:[value+v%3D10+t%3Dint],abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=6
   [junit4]   2> 1038150 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],[shard],abs(val_i)&fl=[explain],x_alias:[value+v%3D10+t%3Dint]&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038151 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],[shard]&fl=[explain],x_alias:[value+v%3D10+t%3Dint]&fl=abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038154 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid]&fl=[shard]&fl=[explain]&fl=x_alias:[value+v%3D10+t%3Dint]&fl=abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038155 INFO  (TEST-TestPseudoReturnFields.testAugmentersRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testAugmentersRTG
   [junit4]   2> 1038157 INFO  (TEST-TestPseudoReturnFields.testFunctionsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testFunctionsRTG
   [junit4]   2> 1038159 INFO  (TEST-TestPseudoReturnFields.testFunctionsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395267616768)]} 0 1
   [junit4]   2> 1038163 INFO  (TEST-TestPseudoReturnFields.testFunctionsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&id=42&wt=xml&fl=log(val_i),abs(val_i)} status=0 QTime=3
   [junit4]   2> 1038164 INFO  (TEST-TestPseudoReturnFields.testFunctionsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&id=42&wt=xml&fl=log(val_i)&fl=abs(val_i)} status=0 QTime=0
   [junit4]   2> 1038170 INFO  (TEST-TestPseudoReturnFields.testFunctionsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a2cc15[collection1] realtime]
   [junit4]   2> 1038171 INFO  (TEST-TestPseudoReturnFields.testFunctionsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&id=99&wt=xml&fl=log(val_i),abs(val_i)} status=0 QTime=5
   [junit4]   2> 1038172 INFO  (TEST-TestPseudoReturnFields.testFunctionsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&id=99&wt=xml&fl=log(val_i)&fl=abs(val_i)} status=0 QTime=0
   [junit4]   2> 1038174 INFO  (TEST-TestPseudoReturnFields.testFunctionsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testFunctionsRTG
   [junit4]   2> 1038176 INFO  (TEST-TestPseudoReturnFields.testFunctions-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testFunctions
   [junit4]   2> 1038177 INFO  (TEST-TestPseudoReturnFields.testFunctions-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395286491136)]} 0 0
   [junit4]   2> 1038178 INFO  (TEST-TestPseudoReturnFields.testFunctions-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=log(val_i)&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038179 INFO  (TEST-TestPseudoReturnFields.testFunctions-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=log(val_i),abs(val_i)&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038180 INFO  (TEST-TestPseudoReturnFields.testFunctions-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=log(val_i)&fl=abs(val_i)&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038181 INFO  (TEST-TestPseudoReturnFields.testFunctions-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testFunctions
   [junit4]   2> 1038184 INFO  (TEST-TestPseudoReturnFields.testScoreAndExplicitRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testScoreAndExplicitRealFieldsRTG
   [junit4]   2> 1038185 INFO  (TEST-TestPseudoReturnFields.testScoreAndExplicitRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395294879744)]} 0 0
   [junit4]   2> 1038186 INFO  (TEST-TestPseudoReturnFields.testScoreAndExplicitRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=score,val_i&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038187 INFO  (TEST-TestPseudoReturnFields.testScoreAndExplicitRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=score,val_i&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038188 INFO  (TEST-TestPseudoReturnFields.testScoreAndExplicitRealFieldsRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testScoreAndExplicitRealFieldsRTG
   [junit4]   2> 1038191 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testGlobsAndExplicit
   [junit4]   2> 1038192 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395302219776)]} 0 0
   [junit4]   2> 1038192 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=val_*,id&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038194 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*,subj*,id&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038195 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*&fl=id&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038196 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*,id&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038197 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testGlobsAndExplicit
   [junit4]   2> 1038201 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testAugmentersAndExplicitRTG
   [junit4]   2> 1038202 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395313754112)]} 0 1
   [junit4]   2> 1038203 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id,[docid],[explain],x_alias:[value+v%3D10+t%3Dint],abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038205 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id,[docid],abs(val_i)&fl=[explain],x_alias:[value+v%3D10+t%3Dint]&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038206 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id&fl=[docid]&fl=[explain]&fl=x_alias:[value+v%3D10+t%3Dint]&fl=abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038217 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a3d46c[collection1] realtime]
   [junit4]   2> 1038219 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id,[docid],[explain],x_alias:[value+v%3D10+t%3Dint],abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=11
   [junit4]   2> 1038221 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id,[docid],abs(val_i)&fl=[explain],x_alias:[value+v%3D10+t%3Dint]&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038223 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id&fl=[docid]&fl=[explain]&fl=x_alias:[value+v%3D10+t%3Dint]&fl=abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038226 INFO  (TEST-TestPseudoReturnFields.testAugmentersAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testAugmentersAndExplicitRTG
   [junit4]   2> 1038228 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testGlobsAndExplicitRTG
   [junit4]   2> 1038232 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395343114240)]} 0 3
   [junit4]   2> 1038232 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=val_*,id&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038233 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*,subj*,id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038236 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*&fl=id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038237 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*,id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038238 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=val_*,id&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038239 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*,subj*,id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038240 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*&fl=id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038242 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=subj*,id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038242 INFO  (TEST-TestPseudoReturnFields.testGlobsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testGlobsAndExplicitRTG
   [junit4]   2> 1038245 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testAugmentersGlobsExplicitAndScoreOhMyRTG
   [junit4]   2> 1038248 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395360940032)]} 0 2
   [junit4]   2> 1038248 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*,[docid],[explain],abs(val_i),id,subj*,score&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038251 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=[docid]&fl=[explain]&fl=abs(val_i)&fl=id&fl=subj*&fl=score&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038257 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@582c1[collection1] realtime]
   [junit4]   2> 1038258 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*,[docid],[explain],abs(val_i),id,subj*,score&qt=/get&id=99&wt=xml} status=0 QTime=5
   [junit4]   2> 1038259 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=[docid]&fl=[explain]&fl=abs(val_i)&fl=id&fl=subj*&fl=score&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038261 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[explain],[docid],abs(val_i),score,id,subj*,val_*&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038264 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[explain]&fl=[docid]&fl=abs(val_i)&fl=score&fl=id&fl=subj*&fl=val_*&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038268 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[explain],[docid],abs(val_i),score,id,subj*,val_*&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038270 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[explain]&fl=[docid]&fl=abs(val_i)&fl=score&fl=id&fl=subj*&fl=val_*&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038280 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*,id,score,[explain],abs(val_i),subj*,[docid]&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038286 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=id&fl=score&fl=[explain]&fl=abs(val_i)&fl=subj*&fl=[docid]&qt=/get&id=42&wt=xml} status=0 QTime=2
   [junit4]   2> 1038287 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*,id,score,[explain],abs(val_i),subj*,[docid]&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038289 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=val_*&fl=id&fl=score&fl=[explain]&fl=abs(val_i)&fl=subj*&fl=[docid]&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038291 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=abs(val_i),[docid],[explain],score,val_*,subj*,id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038294 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=abs(val_i)&fl=[docid]&fl=[explain]&fl=score&fl=val_*&fl=subj*&fl=id&qt=/get&id=42&wt=xml} status=0 QTime=1
   [junit4]   2> 1038296 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=abs(val_i),[docid],[explain],score,val_*,subj*,id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038298 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=abs(val_i)&fl=[docid]&fl=[explain]&fl=score&fl=val_*&fl=subj*&fl=id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038300 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[explain],id,[docid],abs(val_i),subj*,score,val_*&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038302 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[explain]&fl=id&fl=[docid]&fl=abs(val_i)&fl=subj*&fl=score&fl=val_*&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038303 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[explain],id,[docid],abs(val_i),subj*,score,val_*&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038305 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[explain]&fl=id&fl=[docid]&fl=abs(val_i)&fl=subj*&fl=score&fl=val_*&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038307 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],subj*,score,val_*,abs(val_i),[explain],id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038309 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid]&fl=subj*&fl=score&fl=val_*&fl=abs(val_i)&fl=[explain]&fl=id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038312 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],subj*,score,val_*,abs(val_i),[explain],id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038313 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid]&fl=subj*&fl=score&fl=val_*&fl=abs(val_i)&fl=[explain]&fl=id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038314 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=subj*,id,score,val_*,[docid],[explain],abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038316 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=subj*&fl=id&fl=score&fl=val_*&fl=[docid]&fl=[explain]&fl=abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038318 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=subj*,id,score,val_*,[docid],[explain],abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038319 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=subj*&fl=id&fl=score&fl=val_*&fl=[docid]&fl=[explain]&fl=abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038321 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],subj*,abs(val_i),val_*,[explain],score,id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038322 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid]&fl=subj*&fl=abs(val_i)&fl=val_*&fl=[explain]&fl=score&fl=id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038325 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],subj*,abs(val_i),val_*,[explain],score,id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038327 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid]&fl=subj*&fl=abs(val_i)&fl=val_*&fl=[explain]&fl=score&fl=id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038329 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],[explain],subj*,score,abs(val_i),val_*,id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038329 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid]&fl=[explain]&fl=subj*&fl=score&fl=abs(val_i)&fl=val_*&fl=id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038331 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],[explain],subj*,score,abs(val_i),val_*,id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038334 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid]&fl=[explain]&fl=subj*&fl=score&fl=abs(val_i)&fl=val_*&fl=id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038334 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score,[docid],abs(val_i),val_*,subj*,[explain],id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038336 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score&fl=[docid]&fl=abs(val_i)&fl=val_*&fl=subj*&fl=[explain]&fl=id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038337 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score,[docid],abs(val_i),val_*,subj*,[explain],id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038339 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score&fl=[docid]&fl=abs(val_i)&fl=val_*&fl=subj*&fl=[explain]&fl=id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038341 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=subj*,id,[explain],score,val_*,[docid],abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038342 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=subj*&fl=id&fl=[explain]&fl=score&fl=val_*&fl=[docid]&fl=abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038344 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=subj*,id,[explain],score,val_*,[docid],abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038346 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=subj*&fl=id&fl=[explain]&fl=score&fl=val_*&fl=[docid]&fl=abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038347 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=abs(val_i),val_*,[explain],subj*,[docid],score,id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038349 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=abs(val_i)&fl=val_*&fl=[explain]&fl=subj*&fl=[docid]&fl=score&fl=id&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038350 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=abs(val_i),val_*,[explain],subj*,[docid],score,id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038352 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=abs(val_i)&fl=val_*&fl=[explain]&fl=subj*&fl=[docid]&fl=score&fl=id&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038353 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id,val_*,subj*,[docid],score,abs(val_i),[explain]&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038355 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id&fl=val_*&fl=subj*&fl=[docid]&fl=score&fl=abs(val_i)&fl=[explain]&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038356 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id,val_*,subj*,[docid],score,abs(val_i),[explain]&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038357 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=id&fl=val_*&fl=subj*&fl=[docid]&fl=score&fl=abs(val_i)&fl=[explain]&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038359 INFO  (TEST-TestPseudoReturnFields.testAugmentersGlobsExplicitAndScoreOhMyRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testAugmentersGlobsExplicitAndScoreOhMyRTG
   [junit4]   2> 1038363 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testFunctionsAndExplicit
   [junit4]   2> 1038364 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395482574848)]} 0 1
   [junit4]   2> 1038365 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=log(val_i),val_i&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038367 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=log(val_i)&fl=val_i&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038367 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicit-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testFunctionsAndExplicit
   [junit4]   2> 1038373 INFO  (TEST-TestPseudoReturnFields.testScoreAndExplicitRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testScoreAndExplicitRealFields
   [junit4]   2> 1038374 INFO  (TEST-TestPseudoReturnFields.testScoreAndExplicitRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395494109184)]} 0 0
   [junit4]   2> 1038375 INFO  (TEST-TestPseudoReturnFields.testScoreAndExplicitRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=score,val_i&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038376 INFO  (TEST-TestPseudoReturnFields.testScoreAndExplicitRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=score&fl=val_i&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038377 INFO  (TEST-TestPseudoReturnFields.testScoreAndExplicitRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=val_i&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038379 INFO  (TEST-TestPseudoReturnFields.testScoreAndExplicitRealFields-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testScoreAndExplicitRealFields
   [junit4]   2> 1038381 INFO  (TEST-TestPseudoReturnFields.testAugmenters-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testAugmenters
   [junit4]   2> 1038382 INFO  (TEST-TestPseudoReturnFields.testAugmenters-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395501449216)]} 0 0
   [junit4]   2> 1038382 INFO  (TEST-TestPseudoReturnFields.testAugmenters-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&fl=[docid]&rows=1&wt=xml} hits=5 status=0 QTime=0
   [junit4]   2> 1038384 INFO  (TEST-TestPseudoReturnFields.testAugmenters-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],[shard],[explain],x_alias:[value+v%3D10+t%3Dint]&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038385 INFO  (TEST-TestPseudoReturnFields.testAugmenters-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid],[shard]&fl=[explain],x_alias:[value+v%3D10+t%3Dint]&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038389 INFO  (TEST-TestPseudoReturnFields.testAugmenters-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=[docid]&fl=[shard]&fl=[explain]&fl=x_alias:[value+v%3D10+t%3Dint]&q=*:*&rows=1} hits=5 status=0 QTime=0
   [junit4]   2> 1038390 INFO  (TEST-TestPseudoReturnFields.testAugmenters-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testAugmenters
   [junit4]   2> 1038393 INFO  (TEST-TestPseudoReturnFields.testDocIdAugmenterRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDocIdAugmenterRTG
   [junit4]   2> 1038394 INFO  (TEST-TestPseudoReturnFields.testDocIdAugmenterRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395514032128)]} 0 0
   [junit4]   2> 1038395 INFO  (TEST-TestPseudoReturnFields.testDocIdAugmenterRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=[docid]&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038396 INFO  (TEST-TestPseudoReturnFields.testDocIdAugmenterRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=[docid]&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038397 INFO  (TEST-TestPseudoReturnFields.testDocIdAugmenterRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDocIdAugmenterRTG
   [junit4]   2> 1038403 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testFunctionsAndScoreRTG
   [junit4]   2> 1038404 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395525566464)]} 0 0
   [junit4]   2> 1038406 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score&fl=log(val_i)&fl=abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038407 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score&fl=log(val_i),abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038407 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score,log(val_i)&fl=abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038409 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score,log(val_i),abs(val_i)&qt=/get&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038430 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c492a3[collection1] realtime]
   [junit4]   2> 1038437 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score&fl=log(val_i)&fl=abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=27
   [junit4]   2> 1038438 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score&fl=log(val_i),abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038439 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score,log(val_i)&fl=abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038440 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=score,log(val_i),abs(val_i)&qt=/get&id=99&wt=xml} status=0 QTime=0
   [junit4]   2> 1038440 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndScoreRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testFunctionsAndScoreRTG
   [junit4]   2> 1038442 INFO  (TEST-TestPseudoReturnFields.testMultiValuedRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultiValuedRTG
   [junit4]   2> 1038445 INFO  (TEST-TestPseudoReturnFields.testMultiValuedRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395566460928)]} 0 2
   [junit4]   2> 1038446 INFO  (TEST-TestPseudoReturnFields.testMultiValuedRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=val_ss:val_i,+val2_ss:10,+subject&id=42&wt=xml} status=0 QTime=0
   [junit4]   2> 1038454 INFO  (TEST-TestPseudoReturnFields.testMultiValuedRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@195a2ec[collection1] realtime]
   [junit4]   2> 1038457 INFO  (TEST-TestPseudoReturnFields.testMultiValuedRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&fl=val_ss:val_i,+val2_ss:10,+subject&id=99&wt=xml} status=0 QTime=10
   [junit4]   2> 1038457 INFO  (TEST-TestPseudoReturnFields.testMultiValuedRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultiValuedRTG
   [junit4]   2> 1038462 INFO  (TEST-TestPseudoReturnFields.testFilterAndOneRealFieldRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testFilterAndOneRealFieldRTG
   [junit4]   2> 1038471 INFO  (TEST-TestPseudoReturnFields.testFilterAndOneRealFieldRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395588481024)]} 0 7
   [junit4]   2> 1038479 INFO  (TEST-TestPseudoReturnFields.testFilterAndOneRealFieldRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@113c821[coll

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

###Starting testFunctionsAndExplicitRTG
   [junit4]   2> 1038550 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[99 (1587293395677609984)]} 0 1
   [junit4]   2> 1038551 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=log(val_i),val_i&qt=/get&wt=xml&id=42} status=0 QTime=0
   [junit4]   2> 1038559 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=log(val_i)&fl=val_i&qt=/get&wt=xml&id=42} status=0 QTime=0
   [junit4]   2> 1038566 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1265805[collection1] realtime]
   [junit4]   2> 1038568 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=log(val_i),val_i&qt=/get&wt=xml&id=99} status=0 QTime=7
   [junit4]   2> 1038570 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={fl=log(val_i)&fl=val_i&qt=/get&wt=xml&id=99} status=0 QTime=0
   [junit4]   2> 1038571 INFO  (TEST-TestPseudoReturnFields.testFunctionsAndExplicitRTG-seed#[BD018FE78F815C8E]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testFunctionsAndExplicitRTG
   [junit4]   2> 1038572 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1038572 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=15317367
   [junit4]   2> 1038572 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1038572 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3dfbde: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@15b7fa7
   [junit4]   2> 1038584 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1038584 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@85b9f6: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@fe3ea1
   [junit4]   2> 1038592 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1038592 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e31039: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@125fe52
   [junit4]   2> 1038593 INFO  (coreCloseExecutor-3607-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@17b1998
   [junit4]   2> 1038593 INFO  (coreCloseExecutor-3607-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=24844696
   [junit4]   2> 1038593 INFO  (coreCloseExecutor-3607-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7d59f9: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@a136af
   [junit4]   2> 1038605 INFO  (coreCloseExecutor-3607-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 1038605 INFO  (coreCloseExecutor-3607-thread-1) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@23a96f commitCommandVersion:0
   [junit4]   2> 1038609 INFO  (SUITE-TestPseudoReturnFields-seed#[BD018FE78F815C8E]-worker) [    ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70), sim=RandomSimilarity(queryNorm=true): {}, locale=zh-CN, timezone=America/Blanc-Sablon
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=188270608,total=395575296
   [junit4]   2> NOTE: All tests run in this JVM: [TestStressInPlaceUpdates, ClusterStateTest, TestZkAclsWithHadoopAuth, DeleteNodeTest, UnloadDistributedZkTest, TestXmlQParserPlugin, TestLazyCores, TestRandomRequestDistribution, MultiTermTest, TestSegmentSorting, TestLMDirichletSimilarityFactory, PrimUtilsTest, TestNumericTokenStream, SortByFunctionTest, LeaderElectionTest, TestHalfAndHalfDocValues, TestPushWriter, ConfigSetsAPITest, TriggerIntegrationTest, DateFieldTest, TestExportWriter, TestRangeQuery, TestFieldCacheVsDocValues, TestCoreDiscovery, TestBinaryResponseWriter, TestSolr4Spatial, SolrMetricReporterTest, XmlUpdateRequestHandlerTest, TestTolerantSearch, DistribJoinFromCollectionTest, PeerSyncReplicationTest, TestLegacyNumericRangeQueryBuilder, TestDownShardTolerantSearch, TestManagedResourceStorage, TestPivotHelperCode, ConnectionReuseTest, TestLegacyFieldReuse, DistribCursorPagingTest, DistanceUnitsTest, TestHighlightDedupGrouping, BadIndexSchemaTest, SearchHandlerTest, TestRandomDVFaceting, MinimalSchemaTest, TestNestedDocsSort, TestQueryWrapperFilter, TestCloudSchemaless, TestStandardQParsers, NodeLostTriggerTest, HdfsRestartWhileUpdatingTest, TimeZoneUtilsTest, FastVectorHighlighterTest, ManagedSchemaRoundRobinCloudTest, RequiredFieldsTest, SolrXmlInZkTest, TestSolrCoreSnapshots, TestMiniSolrCloudClusterSSL, TestRecoveryHdfs, ExecutePlanActionTest, ClassificationUpdateProcessorTest, SpellCheckCollatorTest, SyncSliceTest, HdfsCollectionsAPIDistributedZkTest, TestCustomStream, TestAddFieldRealTimeGet, ConfigureRecoveryStrategyTest, DistanceFunctionTest, TestIndexingPerformance, LeaderInitiatedRecoveryOnShardRestartTest, OverriddenZkACLAndCredentialsProvidersTest, RankQueryTest, TestQuerySenderListener, MetricsHandlerTest, DistributedTermsComponentTest, TestFieldCacheSort, TestFilteredDocIdSet, TestPointFields, DirectoryFactoryTest, PathHierarchyTokenizerFactoryTest, HdfsThreadLeakTest, SuggesterWFSTTest, DateMathParserTest, TestPHPSerializedResponseWriter, BasicAuthIntegrationTest, ClusterStateUpdateTest, TestStressRecovery, JSONWriterTest, SolrCoreMetricManagerTest, BJQParserTest, TestRTimerTree, HdfsDirectoryFactoryTest, TestLFUCache, CollectionTooManyReplicasTest, TestMissingGroups, RecoveryZkTest, TestSolrCloudWithDelegationTokens, TestSimpleTextCodec, NotRequiredUniqueKeyTest, XsltUpdateRequestHandlerTest, ZkStateReaderTest, TestSolrDeletionPolicy2, TestUpdate, TestCloudSearcherWarming, TestExpandComponent, SolrSlf4jReporterTest, CSVRequestHandlerTest, SolrJmxReporterCloudTest, TestPseudoReturnFields]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestPseudoReturnFields -Dtests.seed=BD018FE78F815C8E -Dtests.slow=true -Dtests.locale=zh-CN -Dtests.timezone=America/Blanc-Sablon -Dtests.asserts=true -Dtests.file.encoding=Cp1252
   [junit4] ERROR   0.00s J0 | TestPseudoReturnFields (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog\tlog.0000000000000000000: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001\init-core-data-001\tlog\tlog.0000000000000000000
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.2-Windows\solr\build\solr-core\test\J0\temp\solr.search.TestPseudoReturnFields_BD018FE78F815C8E-001
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([BD018FE78F815C8E]:0)
   [junit4]    > 	at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [236/751 (1!)] on J0 in 1.04s, 30 tests, 1 error <<< FAILURES!

[...truncated 44905 lines...]

Mime
View raw message