You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/05/22 04:22:24 UTC
[JENKINS-EA] Lucene-Solr-master-Linux
(64bit/jdk-13-ea+shipilev-fastdebug) - Build # 24126 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/24126/
Java: 64bit/jdk-13-ea+shipilev-fastdebug -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.util.hll.BigEndianAscendingWordSerializerTest
Error Message:
The test or suite printed 837374 bytes to stdout and stderr, even though the limit was set to 8192 bytes. Increase the limit with @Limit, ignore it completely with @SuppressSysoutChecks or run with -Dtests.verbose=true
Stack Trace:
java.lang.AssertionError: The test or suite printed 837374 bytes to stdout and stderr, even though the limit was set to 8192 bytes. Increase the limit with @Limit, ignore it completely with @SuppressSysoutChecks or run with -Dtests.verbose=true
at __randomizedtesting.SeedInfo.seed([7438CD45BEF667A3]:0)
at org.apache.lucene.util.TestRuleLimitSysouts.afterIfSuccessful(TestRuleLimitSysouts.java:282)
at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterIfSuccessful(TestRuleAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:37)
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.base/java.lang.Thread.run(Thread.java:830)
Build Log:
[...truncated 12994 lines...]
[junit4] Suite: org.apache.solr.util.hll.BigEndianAscendingWordSerializerTest
[junit4] 2> 648809 INFO (READER18) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648809 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 648809 INFO (READER17) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648809 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 648809 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 648809 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648809 INFO (READER20) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648809 INFO (WRITER10) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@bdc6d97[collection1] main]
[junit4] 2> 648810 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648810 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648810 INFO (READER20) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648810 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 648810 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648810 INFO (READER20) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648810 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648810 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648810 INFO (WRITER10) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 648810 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=6
[junit4] 2> 648813 INFO (searcherExecutor-3353-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@bdc6d97[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_14(9.0.0):C19:[diagnostics={os.arch=amd64, mergeFactor=2, java.runtime.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, os=Linux, timestamp=1558494092415, lucene.version=9.0.0, java.vm.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, java.version=13-testing, mergeMaxNumSegments=-1, os.version=4.18.0-17-generic, java.vendor=Aleksey Shipilev, source=merge}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 648813 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=19
[junit4] 2> 648813 INFO (WRITER10) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 355
[junit4] 2> 648813 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=29
[junit4] 2> 648814 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=20
[junit4] 2> 648814 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648814 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=11
[junit4] 2> 648814 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648814 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=20
[junit4] 2> 648814 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648814 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648814 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:23&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648814 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 QTime=12
[junit4] 2> 648815 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=21
[junit4] 2> 648815 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=12
[junit4] 2> 648815 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=21
[junit4] 2> 648815 INFO (READER11) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=21
[junit4] 2> 648815 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=12
[junit4] 2> 648815 INFO (WRITER1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d087e1f[collection1] realtime]
[junit4] 2> 648815 INFO (READER24) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=21
[junit4] 2> 648815 INFO (READER24) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648815 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648815 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=id:22 (-1634199501580271616)} 0 352
[junit4] 2> 648816 INFO (READER11) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648816 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648816 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=13
[junit4] 2> 648816 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648816 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648816 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648816 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648816 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648816 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=1
[junit4] 2> 648816 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=13
[junit4] 2> 648816 INFO (READER24) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648816 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648816 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648816 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=1
[junit4] 2> 648816 INFO (READER24) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648816 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648816 INFO (READER24) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648816 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 648816 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648816 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648816 INFO (READER24) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648816 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648817 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648817 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648817 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 648817 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 648817 INFO (WRITER4) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1634199501217464322,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 648817 INFO (READER24) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648817 INFO (WRITER4) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@758cb6c4 commitCommandVersion:1634199501217464322
[junit4] 2> 648817 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648817 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648817 INFO (READER24) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648817 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 648817 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 648817 INFO (READER15) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=15
[junit4] 2> 648817 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 QTime=15
[junit4] 2> 648817 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:22&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648817 INFO (READER13) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=15
[junit4] 2> 648817 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=26
[junit4] 2> 648818 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648818 INFO (WRITER19) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[22 (1634199501583417347)]} 0 354
[junit4] 2> 648818 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=2
[junit4] 2> 648818 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=3
[junit4] 2> 648818 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 648818 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 648818 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=16
[junit4] 2> 648819 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=1 status=0 QTime=2
[junit4] 2> 648819 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=1 status=0 QTime=3
[junit4] 2> 648819 INFO (WRITER24) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1634199501445005312,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 648819 INFO (WRITER23) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[13 (1634199501583417346)]} 0 355
[junit4] 2> 648819 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648819 INFO (WRITER9) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[20 (1634199501583417345)]} 0 355
[junit4] 2> 648819 INFO (WRITER14) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[17 (1634199501583417344)]} 0 355
[junit4] 2> 648819 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=9
[junit4] 2> 648819 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648819 INFO (WRITER13) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[16 (1634199501583417350)]} 0 352
[junit4] 2> 648819 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648819 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648820 INFO (WRITER14) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1634199501586563079,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 648820 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=10
[junit4] 2> 648820 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=2
[junit4] 2> 648820 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=1 status=0 QTime=10
[junit4] 2> 648820 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=6
[junit4] 2> 648820 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=6
[junit4] 2> 648820 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=10
[junit4] 2> 648820 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648820 INFO (WRITER12) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2 (1634199501583417348)]} 0 356
[junit4] 2> 648821 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=6
[junit4] 2> 648821 INFO (READER18) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=10
[junit4] 2> 648821 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=1
[junit4] 2> 648821 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER18) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648821 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648821 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648821 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648821 INFO (READER18) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648821 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (WRITER13) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5 (1634199501588660224)]} 0 1
[junit4] 2> 648821 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=1
[junit4] 2> 648821 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648821 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648821 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648821 INFO (WRITER23) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[23 (1634199501588660225)]} 0 1
[junit4] 2> 648822 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648822 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648822 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648822 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648822 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:24&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648822 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[12 (1634199501585514496)]} 0 354
[junit4] 2> 648822 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648822 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648822 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648822 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648822 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648822 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648822 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648823 INFO (WRITER12) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[15 (-1634199501589708800)]} 0 2
[junit4] 2> 648823 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=6
[junit4] 2> 648823 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648823 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648823 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (WRITER18) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{delete=[8 (-1634199501586563074)]} 0 138
[junit4] 2> 648823 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=2
[junit4] 2> 648823 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=3
[junit4] 2> 648823 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 648823 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648823 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:24&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648824 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648824 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648824 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648824 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648824 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648824 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648824 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=1
[junit4] 2> 648824 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648824 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648824 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648824 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (WRITER15) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[9 (1634199501584465920)]} 0 357
[junit4] 2> 648825 INFO (WRITER17) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[20 (1634199501586563080)]} 0 360
[junit4] 2> 648825 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648825 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=1
[junit4] 2> 648825 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648825 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648825 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648825 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648825 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648825 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648825 INFO (READER17) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:22&omitHeader=true&wt=json} hits=1 status=0 QTime=15
[junit4] 2> 648825 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648825 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER17) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=11
[junit4] 2> 648825 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648825 INFO (READER17) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER12) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER16) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER17) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 648825 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648825 INFO (READER17) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:24&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648826 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER17) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=5
[junit4] 2> 648826 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648826 INFO (READER17) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648826 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648826 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER17) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648826 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648826 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=1
[junit4] 2> 648826 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=1 status=0 QTime=7
[junit4] 2> 648826 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648826 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648826 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (WRITER20) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[10 (1634199501583417349)]} 0 359
[junit4] 2> 648826 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648826 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:22&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648826 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648826 INFO (READER20) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=16
[junit4] 2> 648827 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648827 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER20) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=8
[junit4] 2> 648827 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=8
[junit4] 2> 648827 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER20) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648827 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648827 INFO (READER20) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648827 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648827 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648827 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER20) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=1
[junit4] 2> 648827 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:23&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648827 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648827 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648827 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648827 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648827 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 648827 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648828 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648828 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=11
[junit4] 2> 648828 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648828 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648828 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648828 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648828 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648828 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648828 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648828 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648828 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648828 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648828 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648829 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648829 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648829 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648829 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648829 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648829 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648829 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648829 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648829 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648829 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648829 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648829 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648829 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER13) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=12
[junit4] 2> 648829 INFO (READER23) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER22) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648829 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648829 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER21) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648829 INFO (READER9) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648830 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=5
[junit4] 2> 648830 INFO (READER20) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=2
[junit4] 2> 648830 INFO (READER11) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=14
[junit4] 2> 648830 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648830 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=12
[junit4] 2> 648830 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=12
[junit4] 2> 648830 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[7 (1634199501584465921)]} 0 362
[junit4] 2> 648830 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648830 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648830 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648830 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[19 (1634199501598097408)]} 0 0
[junit4] 2> 648830 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648830 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648830 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648830 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 648830 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648831 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[13 (1634199501598097409)]} 0 0
[junit4] 2> 648831 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=1 status=0 QTime=1
[junit4] 2> 648831 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:24&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648831 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648831 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648831 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
[junit4] 2> 648831 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648832 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648832 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:23&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648832 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648832 INFO (READER10) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER15) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=14
[junit4] 2> 648832 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=1 status=0 QTime=0
[junit4] 2> 648832 INFO (READER15) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER14) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER15) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER24) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=15
[junit4] 2> 648832 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER15) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648832 INFO (READER15) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:16&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648833 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=15
[junit4] 2> 648833 INFO (WRITER7) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3 (1634199501584465923)]} 0 364
[junit4] 2> 648833 INFO (READER15) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 648833 INFO (READER20) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=3
[junit4] 2> 648833 INFO (READER15) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 648833 INFO (READER25) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 648833 INFO (READER19) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 648833 INFO (READER15) [ ] o.a.s.c.
[...truncated too long message...]
={timestamp=1558494093023, java.vendor=Aleksey Shipilev, os=Linux, os.version=4.18.0-17-generic, java.runtime.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, java.version=13-testing}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1r(9.0.0):C2:[diagnostics={timestamp=1558494093031, java.vendor=Aleksey Shipilev, os=Linux, os.version=4.18.0-17-generic, java.runtime.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, java.version=13-testing}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1o(9.0.0):C1:[diagnostics={timestamp=1558494093033, java.vendor=Aleksey Shipilev, os=Linux, os.version=4.18.0-17-generic, java.runtime.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, java.version=13-testing}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1q(9.0.0):C1:[diagnostics={timestamp=1558494093037, java.vendor=Aleksey Shipilev, os=Linux, os.version=4.18.0-17-generic, java.runtime.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, java.version=13-testing}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1n(9.0.0):C1:[diagnostics={timestamp=1558494093043, java.vendor=Aleksey Shipilev, os=Linux, os.version=4.18.0-17-generic, java.runtime.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, java.version=13-testing}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1s(9.0.0):C1:[diagnostics={timestamp=1558494093050, java.vendor=Aleksey Shipilev, os=Linux, os.version=4.18.0-17-generic, java.runtime.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=13-testing+0-builds.shipilev.net-openjdk-jdk-b845-20190430-jdk-1318, java.version=13-testing}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 649368 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 491
[junit4] 2> 649371 INFO (TEST-TestStressVersions.testStressGetRealtimeVersions-seed#[7438CD45BEF667A3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStressGetRealtimeVersions
[junit4] 2> 649371 INFO (SUITE-TestStressVersions-seed#[7438CD45BEF667A3]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 649371 INFO (SUITE-TestStressVersions-seed#[7438CD45BEF667A3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=58371203
[junit4] 2> 649383 INFO (coreCloseExecutor-3358-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4d885472
[junit4] 2> 649383 INFO (coreCloseExecutor-3358-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=4d885472
[junit4] 2> 649383 INFO (coreCloseExecutor-3358-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5ae47087: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@7ec92bfa
[junit4] 2> 649455 INFO (SUITE-TestStressVersions-seed#[7438CD45BEF667A3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 649455 INFO (SUITE-TestStressVersions-seed#[7438CD45BEF667A3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@58bf9f67: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1a8b7e16
[junit4] 2> 649466 INFO (SUITE-TestStressVersions-seed#[7438CD45BEF667A3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 649466 INFO (SUITE-TestStressVersions-seed#[7438CD45BEF667A3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@29f1ad93: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@75d621b7
[junit4] 2> 649472 INFO (SUITE-TestStressVersions-seed#[7438CD45BEF667A3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 649473 INFO (SUITE-TestStressVersions-seed#[7438CD45BEF667A3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2853b387: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@342c4f87
[junit4] 2> 649493 INFO (SUITE-TestStressVersions-seed#[7438CD45BEF667A3]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=27, maxMBSortInHeap=5.225303750188081, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@49c7881d), locale=ig, timezone=Europe/Podgorica
[junit4] 2> NOTE: Linux 4.18.0-17-generic amd64/Aleksey Shipilev 13-testing (64-bit)/cpus=8,threads=1,free=123472296,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [DaemonStreamApiTest, DeleteStatusTest, TestFacetMethods, TestRestManager, TestUnifiedSolrHighlighter, BasicFunctionalityTest, TestAuthorizationFramework, TestCryptoKeys, TestDirectoryFactory, SpatialRPTFieldTypeTest, PingRequestHandlerTest, TriggerEventQueueTest, HdfsCollectionsAPIDistributedZkTest, OverseerTaskQueueTest, TestPayloadCheckQParserPlugin, LargeFieldTest, TestUseDocValuesAsStored2, LeaderFailureAfterFreshStartTest, ShardsWhitelistTest, CircularListTest, TestHighlightDedupGrouping, MoveReplicaHDFSTest, TestSnapshotCloudManager, TestReloadAndDeleteDocs, OutOfBoxZkACLAndCredentialsProvidersTest, TestReqParamsAPI, BadCopyFieldTest, ImplicitSnitchTest, MultiThreadedOCPTest, RestoreTriggerStateTest, CollectionStateFormat2Test, TestNumericTerms64, TestConfigReload, ChangedSchemaMergeTest, SystemLogListenerTest, TestSQLHandlerNonCloud, TestLocalFSCloudBackupRestore, ReplicaListTransformerTest, ScriptEngineTest, TestRemoteStreaming, BinaryUpdateRequestHandlerTest, TestRetrieveFieldsOptimizer, ExternalFileFieldSortTest, DistributedFacetSimpleRefinementLongTailTest, ClassificationUpdateProcessorFactoryTest, DisMaxRequestHandlerTest, RAMDirectoryFactoryTest, SolrJmxReporterCloudTest, PeerSyncTest, TestManagedSchema, CoreSorterTest, MetricTriggerTest, FullHLLTest, DocValuesNotIndexedTest, ComputePlanActionTest, TestRequestForwarding, TestDynamicFieldCollectionResource, SolrMetricReporterTest, TestConfigSetsAPI, AutoAddReplicasIntegrationTest, TestJsonRequest, TestSearcherReuse, ProtectedTermFilterFactoryTest, MoreLikeThisComponentTest, TestUtils, DistributedFacetExistsSmallTest, TestLockTree, TestStressVersions, BigEndianAscendingWordSerializerTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BigEndianAscendingWordSerializerTest -Dtests.seed=7438CD45BEF667A3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ig -Dtests.timezone=Europe/Podgorica -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | BigEndianAscendingWordSerializerTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: The test or suite printed 837374 bytes to stdout and stderr, even though the limit was set to 8192 bytes. Increase the limit with @Limit, ignore it completely with @SuppressSysoutChecks or run with -Dtests.verbose=true
[junit4] > at __randomizedtesting.SeedInfo.seed([7438CD45BEF667A3]:0)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:830)
[junit4] Completed [144/858 (1!)] on J1 in 1.03s, 5 tests, 1 failure <<< FAILURES!
[...truncated 45696 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/24126/consoleText
[repro] Revision: 87e936f1bb76b89acdf8d0c3071bb43349c0e00c
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=BigEndianAscendingWordSerializerTest -Dtests.seed=7438CD45BEF667A3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ig -Dtests.timezone=Europe/Podgorica -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] BigEndianAscendingWordSerializerTest
[repro] ant compile-test
[...truncated 3310 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.BigEndianAscendingWordSerializerTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=7438CD45BEF667A3 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ig -Dtests.timezone=Europe/Podgorica -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 95 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.util.hll.BigEndianAscendingWordSerializerTest
[repro] Exiting with code 0
[...truncated 43 lines...]