You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/06/24 20:54:53 UTC
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 685 -
Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/685/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.search.TestStressRecovery.testStressRecovery
Error Message:
Captured an uncaught exception in thread: Thread[id=23198, name=READER0, state=RUNNABLE, group=TGRP-TestStressRecovery]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=23198, name=READER0, state=RUNNABLE, group=TGRP-TestStressRecovery]
at __randomizedtesting.SeedInfo.seed([EA5255D08FAF82FD:50683C8D10473DF3]:0)
Caused by: java.lang.RuntimeException: java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([EA5255D08FAF82FD]:0)
at org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:333)
Caused by: java.lang.NullPointerException
at org.apache.solr.update.TransactionLog.lookup(TransactionLog.java:494)
at org.apache.solr.update.UpdateLog.lookup(UpdateLog.java:1014)
at org.apache.solr.handler.component.RealTimeGetComponent.process(RealTimeGetComponent.java:236)
at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:298)
at org.apache.solr.handler.RealTimeGetHandler.handleRequestBody(RealTimeGetHandler.java:46)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2539)
at org.apache.solr.util.TestHarness.query(TestHarness.java:337)
at org.apache.solr.util.TestHarness.query(TestHarness.java:319)
at org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:308)
Build Log:
[...truncated 14095 lines...]
[junit4] Suite: org.apache.solr.search.TestStressRecovery
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.search.TestStressRecovery_EA5255D08FAF82FD-001/init-core-data-001
[junit4] 2> 2329856 INFO (SUITE-TestStressRecovery-seed#[EA5255D08FAF82FD]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2329885 INFO (SUITE-TestStressRecovery-seed#[EA5255D08FAF82FD]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2329892 INFO (SUITE-TestStressRecovery-seed#[EA5255D08FAF82FD]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 2329934 INFO (SUITE-TestStressRecovery-seed#[EA5255D08FAF82FD]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2331249 INFO (SUITE-TestStressRecovery-seed#[EA5255D08FAF82FD]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2331249 INFO (SUITE-TestStressRecovery-seed#[EA5255D08FAF82FD]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2331264 INFO (SUITE-TestStressRecovery-seed#[EA5255D08FAF82FD]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b927fb
[junit4] 2> 2331271 INFO (SUITE-TestStressRecovery-seed#[EA5255D08FAF82FD]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b927fb
[junit4] 2> 2331271 INFO (SUITE-TestStressRecovery-seed#[EA5255D08FAF82FD]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6b927fb
[junit4] 2> 2331273 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2331300 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0
[junit4] 2> 2331315 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2331366 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 2331371 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2331371 INFO (coreLoadExecutor-10592-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@6b927fb
[junit4] 2> 2331371 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2331371 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.search.TestStressRecovery_EA5255D08FAF82FD-001/init-core-data-001/]
[junit4] 2> 2331372 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=7.681640625, floorSegmentMB=2.1435546875, forceMergeDeletesPctAllowed=2.8985562353078542, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, reclaimDeletesWeight=3.1369328810152517
[junit4] 2> 2331374 WARN (coreLoadExecutor-10592-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> 2331418 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 2331418 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2331419 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2331419 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2331420 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=93.048828125, floorSegmentMB=2.068359375, forceMergeDeletesPctAllowed=12.872038491187938, segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.25649064322968973, reclaimDeletesWeight=1.7702861749096406
[junit4] 2> 2331420 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ae5f0c4[collection1] main]
[junit4] 2> 2331420 WARN (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 2331421 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2331421 INFO (coreLoadExecutor-10592-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1604186050952953856
[junit4] 2> 2331427 INFO (searcherExecutor-10593-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ae5f0c4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2331437 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EA5255D08FAF82FD]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2331438 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EA5255D08FAF82FD]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6f87b9d2[collection1] realtime]
[junit4] 2> 2331438 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EA5255D08FAF82FD]) [ ] 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 2
[junit4] 2> 2331439 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EA5255D08FAF82FD]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1604186050971828224,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2331439 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EA5255D08FAF82FD]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@48945aa4 commitCommandVersion:1604186050971828224
[junit4] 2> 2331440 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EA5255D08FAF82FD]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3de4aea7[collection1] main]
[junit4] 2> 2331441 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EA5255D08FAF82FD]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2331445 INFO (searcherExecutor-10593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3de4aea7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2331445 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EA5255D08FAF82FD]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 2331454 INFO (TEST-TestStressRecovery.testStressRecovery-seed#[EA5255D08FAF82FD]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 2331455 INFO (WRITER2) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: BUFFERING replay: false
[junit4] 2> 2331455 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331457 INFO (WRITER2) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: BUFFERING replay: false
[junit4] 2> 2331457 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331457 INFO (WRITER2) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: BUFFERING replay: false
[junit4] 2> 2331457 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331457 INFO (WRITER2) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: BUFFERING replay: false
[junit4] 2> 2331457 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331457 INFO (WRITER2) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: BUFFERING replay: false
[junit4] 2> 2331457 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331457 INFO (WRITER2) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: BUFFERING replay: false
[junit4] 2> 2331457 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331462 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-1&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[10 (-1)]} 0 7
[junit4] 2> 2331462 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-3&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[15 (-3)]} 0 4
[junit4] 2> 2331463 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-4&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[15 (-4)]} 0 0
[junit4] 2> 2331463 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-5&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[15 (-5)]} 0 0
[junit4] 2> 2331463 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-6&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[15 (-6)]} 0 0
[junit4] 2> 2331463 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-7&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[15 (-7)]} 0 0
[junit4] 2> 2331463 WARN (recoveryExecutor-10595-thread-1) [ ] o.a.s.u.UpdateLog Starting log replay tlog{file=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.search.TestStressRecovery_EA5255D08FAF82FD-001/init-core-data-001/tlog/buffer.tlog.0005139265513985608 refcount=2} active=true starting pos=0 inSortedOrder=false
[junit4] 2> 2331463 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331464 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331465 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331465 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331465 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331465 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331465 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331465 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331465 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331465 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331466 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331466 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331466 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-8&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[9 (-8)]} 0 0
[junit4] 2> 2331466 INFO (WRITER0) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331466 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331467 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 2331467 INFO (WRITER0) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331467 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331467 INFO (WRITER0) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331467 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331467 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[9 (-9)]} 0 0
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-10&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[9 (-10)]} 0 0
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 2331468 INFO (READER1) [ ] 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> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
[junit4] 2> 2331468 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
[junit4] 2> 2331468 INFO (WRITER0) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-11&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[1 (-11)]} 0 0
[junit4] 2> 2331469 INFO (WRITER3) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331469 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331470 INFO (WRITER3) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331470 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331470 INFO (WRITER3) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331470 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331470 INFO (WRITER3) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331470 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331470 INFO (WRITER3) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331470 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331470 INFO (WRITER3) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331470 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331470 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-12&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[1 (-12)]} 0 0
[junit4] 2> 2331475 INFO (WRITER1) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-2&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[10 (-2)]} 0 0
[junit4] 2> 2331475 INFO (WRITER9) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331475 INFO (WRITER9) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331477 INFO (WRITER9) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331477 INFO (WRITER9) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331477 INFO (WRITER9) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331477 INFO (WRITER9) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331478 INFO (WRITER9) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331478 INFO (WRITER9) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331478 INFO (WRITER9) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-13&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[18 (-13)]} 0 0
[junit4] 2> 2331478 INFO (WRITER7) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331478 INFO (WRITER7) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331479 INFO (WRITER8) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-14&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[1 (-14)]} 0 0
[junit4] 2> 2331480 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 2331480 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-15&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[2 (-15)]} 0 0
[junit4] 2> 2331480 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 2331482 INFO (WRITER7) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331482 INFO (WRITER7) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331482 INFO (WRITER2) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331483 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331484 INFO (WRITER2) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331484 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331484 INFO (WRITER7) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331484 INFO (WRITER7) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331484 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-17&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[16 (-17)]} 0 0
[junit4] 2> 2331484 INFO (WRITER3) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331484 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331483 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-16&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[2 (-16)]} 0 0
[junit4] 2> 2331484 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331484 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331484 INFO (WRITER2) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331484 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-18&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0 (-18)]} 0 0
[junit4] 2> 2331484 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331484 INFO (WRITER2) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331485 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331487 INFO (WRITER2) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331487 INFO (WRITER2) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331487 INFO (WRITER6) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-19&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[18 (-19)]} 0 0
[junit4] 2> 2331499 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 2331499 INFO (READER3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 2331500 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 2331500 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331500 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331501 INFO (READER2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 QTime=0
[junit4] 2> 2331501 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331501 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331501 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-20&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[18 (-20)]} 0 0
[junit4] 2> 2331501 INFO (WRITER3) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331501 INFO (WRITER3) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331502 INFO (WRITER7) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331502 INFO (WRITER7) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331502 INFO (WRITER7) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331502 INFO (WRITER7) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331503 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331503 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331503 INFO (WRITER7) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-21&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[18 (-21)]} 0 0
[junit4] 2> 2331503 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331503 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331504 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331504 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331504 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331504 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331504 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331504 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331504 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331504 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331504 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331504 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-22&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[16 (-22)]} 0 0
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-23&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[1 (-23)]} 0 0
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-24&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[1 (-24)]} 0 0
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331505 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-25&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[1 (-25)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-26&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[1 (-26)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-27&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[1 (-27)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-28&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[17 (-28)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-29&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[16 (-29)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-30&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[2 (-30)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-31&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[2 (-31)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-32&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[18 (-32)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-33&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[14 (-33)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-34&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[9 (-34)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-35&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[13 (-35)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-36&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[14 (-36)]} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331506 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-37&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[10 (-37)]} 0 0
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-38&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[10 (-38)]} 0 0
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-39&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[10 (-39)]} 0 0
[junit4] 2> 2331511 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-40&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[10 (-40)]} 0 0
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-41&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[13 (-41)]} 0 0
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-42&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[5 (-42)]} 0 0
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331512 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-43&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[10 (-43)]} 0 0
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-44&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[8 (-44)]} 0 0
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331513 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331514 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 2331514 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-45&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[8 (-45)]} 0 0
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-46&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[2 (-46)]} 0 0
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331514 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-47&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[4 (-47)]} 0 0
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-48&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3 (-48)]} 0 0
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331515 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331516 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-49&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[17 (-49)]} 0 0
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-50&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0 (-50)]} 0 0
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331517 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-51&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0 (-51)]} 0 0
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331518 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-52&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0 (-52)]} 0 0
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-53&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0 (-53)]} 0 0
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-54&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[17 (-54)]} 0 0
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-55&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0 (-55)]} 0 0
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331519 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-56&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0 (-56)]} 0 0
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-57&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[1 (-57)]} 0 0
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-58&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[13 (-58)]} 0 0
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-59&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[13 (-59)]} 0 0
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331520 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331521 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331521 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331521 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331521 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331521 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331521 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331521 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331521 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331527 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-60&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[4 (-60)]} 0 6
[junit4] 2> 2331527 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331527 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331528 INFO (READER0) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
[junit4] 2> 2331528 INFO (READER1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
[junit4] 2> 2331528 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-61&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3 (-61)]} 0 0
[junit4] 2> 2331528 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331528 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-62&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[9 (-62)]} 0 0
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-63&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[9 (-63)]} 0 0
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-64&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[9 (-64)]} 0 0
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331529 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-65&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[10 (-65)]} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-66&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[10 (-66)]} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-67&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[2 (-67)]} 0 0
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331530 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331535 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331535 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 2331536 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-68&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[14 (-68)]} 0 0
[junit4] 2> 2331537 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331537 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331539 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331539 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331540 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331540 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331541 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: false
[junit4] 2> 2331541 INFO (WRITER5) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 2331542 INFO (WRITER5) [ ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: APPLYING_BUFFERED replay: fals
[...truncated too long message...]
y-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml
resolve:
jar-checksums:
[mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/null1422226161
[copy] Copying 247 files to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/null1422226161
[delete] Deleting directory /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/null1422226161
check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 126ms :: artifacts dl 4ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 8 | 0 | 0 | 0 || 8 | 0 |
---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...
-jenkins-base:
BUILD SUCCESSFUL
Total time: 96 minutes 34 seconds
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[Lucene-Solr-7.x-Solaris] $ /bin/sh -xe /var/tmp/jenkins8745185635572108508.sh
+ mv lucene/build lucene/build.orig
+ mv solr/build solr/build.orig
+ ANT_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
+ export ANT_HOME
+ PATH=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/bin:/export/home/jenkins/tools/java/64bit/jdk1.8.0/bin:/usr/bin:/usr/sbin
+ export PATH
+ python3.4 -u dev-tools/scripts/reproduceJenkinsFailures.py --no-git https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/685/consoleText
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/685/consoleText
[repro] Revision: 82b793df56c8c9fb50c29f46f39465453a87f2b2
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Encountered IncompleteRead exception, pausing and then retrying...
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/685/consoleText
[repro] Revision: 82b793df56c8c9fb50c29f46f39465453a87f2b2
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Encountered IncompleteRead exception, pausing and then retrying...
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/685/consoleText
[repro] Revision: 82b793df56c8c9fb50c29f46f39465453a87f2b2
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Encountered IncompleteRead exception, aborting after too many retries.
Traceback (most recent call last):
File "/usr/lib/python3.4/http/client.py", line 614, in _readinto_chunked
chunk_left = self._read_next_chunk_size()
File "/usr/lib/python3.4/http/client.py", line 559, in _read_next_chunk_size
return int(line, 16)
ValueError: invalid literal for int() with base 16: b''
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "dev-tools/scripts/reproduceJenkinsFailures.py", line 110, in fetchAndParseJenkinsLog
for rawLine in consoleText:
File "/usr/lib/python3.4/http/client.py", line 500, in read
return super(HTTPResponse, self).read(amt)
File "/usr/lib/python3.4/http/client.py", line 529, in readinto
return self._readinto_chunked(b)
File "/usr/lib/python3.4/http/client.py", line 618, in _readinto_chunked
raise IncompleteRead(bytes(b[0:total_bytes]))
http.client.IncompleteRead: IncompleteRead(0 bytes read)
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "/usr/lib/python3.4/http/client.py", line 614, in _readinto_chunked
chunk_left = self._read_next_chunk_size()
File "/usr/lib/python3.4/http/client.py", line 559, in _read_next_chunk_size
return int(line, 16)
ValueError: invalid literal for int() with base 16: b''
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "dev-tools/scripts/reproduceJenkinsFailures.py", line 110, in fetchAndParseJenkinsLog
for rawLine in consoleText:
File "/usr/lib/python3.4/http/client.py", line 500, in read
return super(HTTPResponse, self).read(amt)
File "/usr/lib/python3.4/http/client.py", line 529, in readinto
return self._readinto_chunked(b)
File "/usr/lib/python3.4/http/client.py", line 618, in _readinto_chunked
raise IncompleteRead(bytes(b[0:total_bytes]))
http.client.IncompleteRead: IncompleteRead(0 bytes read)
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "/usr/lib/python3.4/http/client.py", line 614, in _readinto_chunked
chunk_left = self._read_next_chunk_size()
File "/usr/lib/python3.4/http/client.py", line 559, in _read_next_chunk_size
return int(line, 16)
ValueError: invalid literal for int() with base 16: b''
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "dev-tools/scripts/reproduceJenkinsFailures.py", line 110, in fetchAndParseJenkinsLog
for rawLine in consoleText:
File "/usr/lib/python3.4/http/client.py", line 500, in read
return super(HTTPResponse, self).read(amt)
File "/usr/lib/python3.4/http/client.py", line 529, in readinto
return self._readinto_chunked(b)
File "/usr/lib/python3.4/http/client.py", line 618, in _readinto_chunked
raise IncompleteRead(bytes(b[0:total_bytes]))
http.client.IncompleteRead: IncompleteRead(0 bytes read)
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "dev-tools/scripts/reproduceJenkinsFailures.py", line 294, in <module>
main()
File "dev-tools/scripts/reproduceJenkinsFailures.py", line 244, in main
tests = fetchAndParseJenkinsLog(config.url, numRetries = 2)
File "dev-tools/scripts/reproduceJenkinsFailures.py", line 136, in fetchAndParseJenkinsLog
return fetchAndParseJenkinsLog(url, numRetries - 1)
File "dev-tools/scripts/reproduceJenkinsFailures.py", line 136, in fetchAndParseJenkinsLog
return fetchAndParseJenkinsLog(url, numRetries - 1)
File "dev-tools/scripts/reproduceJenkinsFailures.py", line 139, in fetchAndParseJenkinsLog
raise RuntimeError('ERROR: fetching %s : %s' % (url, e))
RuntimeError: ERROR: fetching https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/685/consoleText : IncompleteRead(0 bytes read)
Build step 'Execute shell' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2