Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-master-Linux/35/ Java: 64bit/jdk-9.0.4 -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed. FAILED: org.apache.solr.cloud.AddReplicaTest.test Error Message: core_node6:{"core":"addreplicatest_coll_shard1_replica_n5","base_url":"https://127.0.0.1:34103/solr","node_name":"127.0.0.1:34103_solr","state":"active","type":"NRT","force_set_state":"false"} Stack Trace: java.lang.AssertionError: core_node6:{"core":"addreplicatest_coll_shard1_replica_n5","base_url":"https://127.0.0.1:34103/solr","node_name":"127.0.0.1:34103_solr","state":"active","type":"NRT","force_set_state":"false"} at __randomizedtesting.SeedInfo.seed([EED4732D0B2C8220:66804CF7A5D0EFD8]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.AddReplicaTest.test(AddReplicaTest.java:85) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at 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:844) FAILED: org.apache.solr.search.TestStressRecovery.testStressRecovery Error Message: Captured an uncaught exception in thread: Thread[id=4080, name=WRITER5, state=RUNNABLE, group=TGRP-TestStressRecovery] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=4080, name=WRITER5, state=RUNNABLE, group=TGRP-TestStressRecovery] at __randomizedtesting.SeedInfo.seed([EED4732D0B2C8220:54EE1A7094C43D2E]:0) Caused by: java.lang.RuntimeException: java.lang.NullPointerException at __randomizedtesting.SeedInfo.seed([EED4732D0B2C8220]:0) at org.apache.solr.search.TestStressRecovery$1.run(TestStressRecovery.java:259) Caused by: java.lang.NullPointerException at org.apache.solr.update.TransactionLog.getLogSizeFromStream(TransactionLog.java:637) at org.apache.solr.update.UpdateLog.getCurrentLogSizeFromStream(UpdateLog.java:299) at org.apache.solr.update.DirectUpdateHandler2.getCurrentTLogSize(DirectUpdateHandler2.java:1007) at org.apache.solr.update.DirectUpdateHandler2.updateDeleteTrackers(DirectUpdateHandler2.java:432) at org.apache.solr.update.DirectUpdateHandler2.delete(DirectUpdateHandler2.java:465) at org.apache.solr.update.processor.RunUpdateProcessor.processDelete(RunUpdateProcessorFactory.java:75) at org.apache.solr.update.processor.UpdateRequestProcessor.processDelete(UpdateRequestProcessor.java:59) at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalDelete(DistributedUpdateProcessor.java:956) at org.apache.solr.update.processor.DistributedUpdateProcessor.versionDelete(DistributedUpdateProcessor.java:1844) at org.apache.solr.update.processor.DistributedUpdateProcessor.doDeleteById(DistributedUpdateProcessor.java:1381) at org.apache.solr.update.processor.DistributedUpdateProcessor.processDelete(DistributedUpdateProcessor.java:1359) at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processDelete(LogUpdateProcessorFactory.java:124) at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleDeleteMap(JsonLoader.java:394) at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleDeleteCommand(JsonLoader.java:311) at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:171) at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:121) at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:84) at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97) at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2510) at org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:125) at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1286) at org.apache.solr.SolrTestCaseJ4.deleteAndGetVersion(SolrTestCaseJ4.java:1464) at org.apache.solr.search.TestStressRecovery$1.run(TestStressRecovery.java:191) Build Log: [...truncated 12823 lines...] [junit4] Suite: org.apache.solr.search.TestStressRecovery [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestStressRecovery_EED4732D0B2C8220-001/init-core-data-001 [junit4] 2> 409668 WARN (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3 [junit4] 2> 409668 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 409669 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 409669 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 409669 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.CdcrUpdateLog [junit4] 2> 409669 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 409669 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 409680 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 409690 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test [junit4] 2> 409769 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 409804 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b36e1b [junit4] 2> 409811 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b36e1b [junit4] 2> 409812 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b36e1b [junit4] 2> 409813 INFO (coreLoadExecutor-936-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 409813 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 409827 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 409834 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 409893 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 409899 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 409900 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@77b36e1b [junit4] 2> 409900 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 409900 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestStressRecovery_EED4732D0B2C8220-001/init-core-data-001/] [junit4] 2> 409901 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=1.2236328125, floorSegmentMB=1.0830078125, forceMergeDeletesPctAllowed=25.314667465913143, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.45713562105281136 [junit4] 2> 409902 WARN (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 409938 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog [junit4] 2> 409938 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 409939 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 409939 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 409940 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=84.1064453125, floorSegmentMB=1.6484375, forceMergeDeletesPctAllowed=12.481199628807262, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 409940 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@27534ebf[collection1] main] [junit4] 2> 409940 WARN (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead. [junit4] 2> 409941 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 409941 INFO (coreLoadExecutor-936-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1600227801151045632 [junit4] 2> 409941 INFO (searcherExecutor-937-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@27534ebf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 409943 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 409944 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EED4732D0B2C8220]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStressRecovery [junit4] 2> 409944 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EED4732D0B2C8220]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 409945 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EED4732D0B2C8220]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@ff13b39[collection1] realtime] [junit4] 2> 409945 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EED4732D0B2C8220]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 0 [junit4] 2> 409945 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EED4732D0B2C8220]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1600227801155239936,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 409945 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EED4732D0B2C8220]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1eda21fc commitCommandVersion:1600227801155239936 [junit4] 2> 409946 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EED4732D0B2C8220]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3b235081[collection1] main] [junit4] 2> 409946 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EED4732D0B2C8220]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 409947 INFO (searcherExecutor-937-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3b235081[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 409947 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EED4732D0B2C8220]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2 [junit4] 2> 409952 INFO (WRITER0) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1600227801162579968,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 409956 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-2&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[126 (-2)]} 0 4 [junit4] 2> 409956 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-1&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[168 (-1)]} 0 4 [junit4] 2> 409956 INFO (WRITER7) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-4&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[126 (-4)]} 0 0 [junit4] 2> 409956 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-3&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[126 (-3)]} 0 4 [junit4] 2> 409956 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-5&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[126 (-5)]} 0 0 [junit4] 2> 409956 INFO (WRITER0) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 409956 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5 [junit4] 2> 409958 INFO (WRITER1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1600227801168871424,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 409958 INFO (WRITER1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 409958 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0 [junit4] 2> 409959 INFO (WRITER3) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1600227801169920000,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 409959 INFO (WRITER3) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 409959 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0 [junit4] 2> 409960 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409960 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409960 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=70&wt=json} status=0 QTime=1 [junit4] 2> 409960 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=76&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=39&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=197&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=119&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=178&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=104&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=153&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (WRITER7) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1600227801170968576,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 409960 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=2 [junit4] 2> 409960 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:160&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409960 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=110&wt=json} status=0 QTime=2 [junit4] 2> 409960 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:62&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409960 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (WRITER7) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 409960 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=136&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:47&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409960 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=107&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:86&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=45&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=72&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:133&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=60&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=181&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:181&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=141&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=177&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:101&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=162&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=58&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=191&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:180&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:168&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=194&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=81&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=120&wt=json} status=0 QTime=0 [junit4] 2> 409961 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=96&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=161&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=121&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=146&wt=json} status=0 QTime=3 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=61&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=187&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:70&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409962 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=173&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:81&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409960 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=3 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=181&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:142&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409962 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=146&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=176&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=121&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=170&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=93&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] 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> 409962 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=31&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=176&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:96&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409962 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=0 [junit4] 2> 409962 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> 409962 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> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=188&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409962 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=119&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=192&wt=json} status=0 QTime=0 [junit4] 2> 409960 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=156&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=111&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=180&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=134&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=159&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409962 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:61&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409962 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:167&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:58&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=182&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:112&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=195&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:143&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=98&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:174&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=114&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:166&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=131&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=181&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:153&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=166&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:109&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 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> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=141&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=60&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:64&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409963 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> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=107&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] 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> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=71&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=143&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=109&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:43&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=94&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=116&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409962 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:46&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409962 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=164&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=99&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=60&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=192&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=56&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=33&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0 [junit4] 2> 409964 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> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:64&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409964 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=67&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=141&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=128&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=182&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:191&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:122&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER1) [ ] 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> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:119&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409964 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:105&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409964 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=131&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=85&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=59&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:103&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409965 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=175&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=113&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=82&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:46&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=165&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=99&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=190&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:96&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409965 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:99&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409963 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409963 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=194&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=32&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=68&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=177&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=62&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=117&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=125&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=119&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=79&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=64&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=169&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=138&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:184&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=60&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=153&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:78&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:86&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=167&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0 [junit4] 2> 409966 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409966 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=64&wt=json} status=0 QTime=0 [junit4] 2> 409965 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=48&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=158&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=80&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409964 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:55&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409967 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=46&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409967 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=138&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:172&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409964 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409967 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=136&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=122&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=139&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:143&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409967 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> 409967 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=143&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:191&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409967 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:191&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409967 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:85&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409967 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:177&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409967 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> 409967 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:64&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409967 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=87&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=104&wt=json} status=0 QTime=0 [junit4] 2> 409967 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:172&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409967 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:190&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=117&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER7) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=175&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER6) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=182&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=114&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=162&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=93&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=104&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=183&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=133&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=53&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:40&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409968 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:71&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=112&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=137&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=196&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=68&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409968 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:55&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=89&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:57&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409968 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:87&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:112&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409968 INFO (READER5) [ ] 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> 409968 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:186&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:113&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409969 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=88&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=195&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=114&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=156&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=97&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409969 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:24&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER5) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=120&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=137&wt=json} status=0 QTime=0 [junit4] 2> 409969 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> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=89&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=179&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=155&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:118&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=158&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:75&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:102&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409969 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=46&wt=json} status=0 QTime=0 [junit4] 2> 409969 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> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=80&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:133&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:128&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=124&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=184&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=132&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=197&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:44&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=96&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:112&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=151&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=97&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=158&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=169&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=151&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:105&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=196&wt=json} status=0 QTime=0 [junit4] 2> 409970 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=160&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=82&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=193&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=29&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=88&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=134&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:85&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=72&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=117&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=81&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=128&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=105&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=174&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=148&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=187&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=77&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=139&wt=json} status=0 QTime=0 [junit4] 2> 409971 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (WRITER7) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 11 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=159&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=101&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=73&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=186&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=78&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=110&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=96&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:185&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:58&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=64&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409972 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:126&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409973 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> 409973 INFO (WRITER8) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1600227801184600064,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 409973 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> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:101&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409973 INFO (WRITER8) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (WRITER8) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0 [junit4] 2> 409973 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=53&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:192&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409973 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:59&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409973 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=52&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=61&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:124&omitHeader=true&wt=json} hits=0 status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=175&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=167&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER8) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=133&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=45&wt=json} status=0 QTime=0 [junit4] 2> 409973 INFO (READER8) [ ] o.a.s.c.S.Requ [...truncated too long message...] dlerBase.handleRequest(RequestHandlerBase.java:199) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2510) [junit4] 2> at org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:125) [junit4] 2> at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1286) [junit4] 2> at org.apache.solr.SolrTestCaseJ4.deleteAndGetVersion(SolrTestCaseJ4.java:1464) [junit4] 2> at org.apache.solr.search.TestStressRecovery$1.run(TestStressRecovery.java:191) [junit4] 2> [junit4] 2> 21797 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EED4732D0B2C8220]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStressRecovery [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestStressRecovery -Dtests.method=testStressRecovery -Dtests.seed=EED4732D0B2C8220 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=gv-IM -Dtests.timezone=Etc/GMT+8 -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 16.1s J0 | TestStressRecovery.testStressRecovery <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=29, name=WRITER6, state=RUNNABLE, group=TGRP-TestStressRecovery] [junit4] > at __randomizedtesting.SeedInfo.seed([EED4732D0B2C8220:54EE1A7094C43D2E]:0) [junit4] > Caused by: java.lang.RuntimeException: java.lang.NullPointerException [junit4] > at __randomizedtesting.SeedInfo.seed([EED4732D0B2C8220]:0) [junit4] > at org.apache.solr.search.TestStressRecovery$1.run(TestStressRecovery.java:259) [junit4] > Caused by: java.lang.NullPointerException [junit4] > at org.apache.solr.update.TransactionLog.getLogSizeFromStream(TransactionLog.java:637) [junit4] > at org.apache.solr.update.UpdateLog.getCurrentLogSizeFromStream(UpdateLog.java:299) [junit4] > at org.apache.solr.update.DirectUpdateHandler2.getCurrentTLogSize(DirectUpdateHandler2.java:1007) [junit4] > at org.apache.solr.update.DirectUpdateHandler2.updateDeleteTrackers(DirectUpdateHandler2.java:432) [junit4] > at org.apache.solr.update.DirectUpdateHandler2.delete(DirectUpdateHandler2.java:465) [junit4] > at org.apache.solr.update.processor.RunUpdateProcessor.processDelete(RunUpdateProcessorFactory.java:75) [junit4] > at org.apache.solr.update.processor.UpdateRequestProcessor.processDelete(UpdateRequestProcessor.java:59) [junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalDelete(DistributedUpdateProcessor.java:956) [junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.versionDelete(DistributedUpdateProcessor.java:1844) [junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.doDeleteById(DistributedUpdateProcessor.java:1381) [junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.processDelete(DistributedUpdateProcessor.java:1359) [junit4] > at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processDelete(LogUpdateProcessorFactory.java:124) [junit4] > at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleDeleteMap(JsonLoader.java:394) [junit4] > at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleDeleteCommand(JsonLoader.java:311) [junit4] > at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:171) [junit4] > at org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:121) [junit4] > at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:84) [junit4] > at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97) [junit4] > at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68) [junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) [junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2510) [junit4] > at org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:125) [junit4] > at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1286) [junit4] > at org.apache.solr.SolrTestCaseJ4.deleteAndGetVersion(SolrTestCaseJ4.java:1464) [junit4] > at org.apache.solr.search.TestStressRecovery$1.run(TestStressRecovery.java:191) [junit4] 2> 21803 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 21803 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=337245473 [junit4] 2> 21803 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 21804 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@103b1e90: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5524f391 [junit4] 2> 21827 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 21827 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5af24513: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@62076b61 [junit4] 2> 21827 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 21827 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6c128059: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@9b29cd [junit4] 2> 21831 INFO (coreCloseExecutor-12-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6cb05f8c [junit4] 2> 21832 INFO (coreCloseExecutor-12-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=6cb05f8c [junit4] 2> 21832 INFO (coreCloseExecutor-12-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6d4fcfd7: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@7e3b7867 [junit4] 2> 21957 INFO (SUITE-TestStressRecovery-seed#[EED4732D0B2C8220]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> May 12, 2018 4:32:51 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene70, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@60d7988e), locale=gv-IM, timezone=Etc/GMT+8 [junit4] 2> NOTE: Linux 4.13.0-39-generic amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=8,threads=1,free=326643416,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [TestStressRecovery] [junit4] Completed [2/10 (1!)] on J0 in 21.34s, 1 test, 1 error <<< FAILURES! [...truncated 45 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1568: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1092: There were test failures: 10 suites, 10 tests, 1 error [seed: EED4732D0B2C8220] Total time: 1 minute 31 seconds [repro] Setting last failure code to 256 [repro] Failures: [repro] 0/5 failed: org.apache.solr.cloud.AddReplicaTest [repro] 1/5 failed: org.apache.solr.search.TestStressRecovery [repro] Exiting with code 256 + mv lucene/build lucene/build.repro + mv solr/build solr/build.repro + mv lucene/build.orig lucene/build + mv solr/build.orig solr/build Archiving artifacts Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac) Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 <Git Blamer> Using GitBlamer to create author and commit information for all warnings. <Git Blamer> GIT_COMMIT=bad0e3f164872c9e96fa366ab2dc1409581d7a34, workspace=/var/lib/jenkins/workspace/Lucene-Solr-BadApples-master-Linux [WARNINGS] Computing warning deltas based on reference build #34 Recording test results Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org