Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1323/
2 tests failed. FAILED: org.apache.lucene.search.TestInetAddressRangeQueries.testRandomBig Error Message: Java heap space Stack Trace: java.lang.OutOfMemoryError: Java heap space at __randomizedtesting.SeedInfo.seed([6AE5E766A0153ED9:EDB29AE9314C4259]:0) at org.apache.lucene.util.bkd.HeapPointWriter.writePackedValue(HeapPointWriter.java:107) at org.apache.lucene.util.bkd.HeapPointWriter.append(HeapPointWriter.java:128) at org.apache.lucene.util.bkd.PointReader.split(PointReader.java:74) at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1791) at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805) at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805) at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805) at org.apache.lucene.util.bkd.BKDWriter.finish(BKDWriter.java:1008) at org.apache.lucene.codecs.lucene60.Lucene60PointsWriter.writeField(Lucene60PointsWriter.java:130) at org.apache.lucene.codecs.PointsWriter.mergeOneField(PointsWriter.java:62) at org.apache.lucene.codecs.lucene60.Lucene60PointsWriter.merge(Lucene60PointsWriter.java:223) at org.apache.lucene.index.SegmentMerger.mergePoints(SegmentMerger.java:187) at org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:136) at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4361) at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3933) at org.apache.lucene.index.SerialMergeScheduler.merge(SerialMergeScheduler.java:40) at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:2082) at org.apache.lucene.index.IndexWriter.doAfterSegmentFlushed(IndexWriter.java:4992) at org.apache.lucene.index.DocumentsWriter$MergePendingEvent.process(DocumentsWriter.java:731) at org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5030) at org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5021) at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1573) at org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1315) at org.apache.lucene.search.BaseRangeFieldQueryTestCase.verify(BaseRangeFieldQueryTestCase.java:190) at org.apache.lucene.search.BaseRangeFieldQueryTestCase.doTestRandom(BaseRangeFieldQueryTestCase.java:160) at org.apache.lucene.search.BaseRangeFieldQueryTestCase.testRandomBig(BaseRangeFieldQueryTestCase.java:75) at org.apache.lucene.search.TestInetAddressRangeQueries.testRandomBig(TestInetAddressRangeQueries.java:81) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) FAILED: org.apache.solr.TestDistributedSearch.test Error Message: Captured an uncaught exception in thread: Thread[id=44906, name=Thread-33143, state=RUNNABLE, group=TGRP-TestDistributedSearch] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=44906, name=Thread-33143, state=RUNNABLE, group=TGRP-TestDistributedSearch] at __randomizedtesting.SeedInfo.seed([96F460804B43D487:1EA05F5AE5BFB97F]:0) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39331//collection1: java.lang.NullPointerException at org.apache.solr.search.grouping.distributed.responseprocessor.StoredFieldsShardResponseProcessor.process(StoredFieldsShardResponseProcessor.java:41) at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:768) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:751) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:423) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2487) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) at org.eclipse.jetty.server.Server.handle(Server.java:534) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([96F460804B43D487]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:610) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:237) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957) at org.apache.solr.TestDistributedSearch$1.run(TestDistributedSearch.java:1140) Build Log: [...truncated 8331 lines...] [junit4] Suite: org.apache.lucene.search.TestInetAddressRangeQueries [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestInetAddressRangeQueries -Dtests.method=testRandomBig -Dtests.seed=6AE5E766A0153ED9 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=de-DE -Dtests.timezone=America/Cayman -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 122s J1 | TestInetAddressRangeQueries.testRandomBig <<< [junit4] > Throwable #1: java.lang.OutOfMemoryError: Java heap space [junit4] > at __randomizedtesting.SeedInfo.seed([6AE5E766A0153ED9:EDB29AE9314C4259]:0) [junit4] > at org.apache.lucene.util.bkd.HeapPointWriter.writePackedValue(HeapPointWriter.java:107) [junit4] > at org.apache.lucene.util.bkd.HeapPointWriter.append(HeapPointWriter.java:128) [junit4] > at org.apache.lucene.util.bkd.PointReader.split(PointReader.java:74) [junit4] > at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1791) [junit4] > at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805) [junit4] > at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805) [junit4] > at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805) [junit4] > at org.apache.lucene.util.bkd.BKDWriter.finish(BKDWriter.java:1008) [junit4] > at org.apache.lucene.codecs.lucene60.Lucene60PointsWriter.writeField(Lucene60PointsWriter.java:130) [junit4] > at org.apache.lucene.codecs.PointsWriter.mergeOneField(PointsWriter.java:62) [junit4] > at org.apache.lucene.codecs.lucene60.Lucene60PointsWriter.merge(Lucene60PointsWriter.java:223) [junit4] > at org.apache.lucene.index.SegmentMerger.mergePoints(SegmentMerger.java:187) [junit4] > at org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:136) [junit4] > at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4361) [junit4] > at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3933) [junit4] > at org.apache.lucene.index.SerialMergeScheduler.merge(SerialMergeScheduler.java:40) [junit4] > at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:2082) [junit4] > at org.apache.lucene.index.IndexWriter.doAfterSegmentFlushed(IndexWriter.java:4992) [junit4] > at org.apache.lucene.index.DocumentsWriter$MergePendingEvent.process(DocumentsWriter.java:731) [junit4] > at org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5030) [junit4] > at org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5021) [junit4] > at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1573) [junit4] > at org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1315) [junit4] > at org.apache.lucene.search.BaseRangeFieldQueryTestCase.verify(BaseRangeFieldQueryTestCase.java:190) [junit4] > at org.apache.lucene.search.BaseRangeFieldQueryTestCase.doTestRandom(BaseRangeFieldQueryTestCase.java:160) [junit4] > at org.apache.lucene.search.BaseRangeFieldQueryTestCase.testRandomBig(BaseRangeFieldQueryTestCase.java:75) [junit4] > at org.apache.lucene.search.TestInetAddressRangeQueries.testRandomBig(TestInetAddressRangeQueries.java:81) [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/misc/test/J1/temp/lucene.search.TestInetAddressRangeQueries_6AE5E766A0153ED9-001 [junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=25137, maxDocsPerChunk=1, blockSize=4), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=25137, blockSize=4)), sim=RandomSimilarity(queryNorm=false): {}, locale=de-DE, timezone=America/Cayman [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=4,threads=1,free=271326872,total=317718528 [junit4] 2> NOTE: All tests run in this JVM: [TestLazyDocument, TestHighFreqTerms, TestIndexSplitter, TestPKIndexSplitter, TestDocValuesStatsCollector, TestInetAddressRangeQueries] [junit4] Completed [13/13 (1!)] on J1 in 136.64s, 4 tests, 1 error <<< FAILURES! [...truncated 1 lines...] [junit4] JVM J1: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/misc/test/temp/junit4-J1-20170612_174903_9697726839819003088903.sysout [junit4] >>> JVM J1 emitted unexpected output (verbatim) ---- [junit4] java.lang.OutOfMemoryError: Java heap space [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/heapdumps/java_pid30482.hprof ... [junit4] Heap dump file created [571547327 bytes in 3.816 secs] [junit4] <<< JVM J1: EOF ---- [...truncated 4500 lines...] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/init-core-data-001 [junit4] 2> 2562074 WARN (SUITE-TestDistributedSearch-seed#[96F460804B43D487]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=32 numCloses=32 [junit4] 2> 2562075 INFO (SUITE-TestDistributedSearch-seed#[96F460804B43D487]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields [junit4] 2> 2562076 INFO (SUITE-TestDistributedSearch-seed#[96F460804B43D487]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9061) [junit4] 2> 2562076 INFO (SUITE-TestDistributedSearch-seed#[96F460804B43D487]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 2562188 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/control/cores/collection1 [junit4] 2> 2562190 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 2562191 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2e006083{/,null,AVAILABLE} [junit4] 2> 2562198 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3e75fd7a{HTTP/1.1,[http/1.1]}{127.0.0.1:34743} [junit4] 2> 2562198 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.e.j.s.Server Started @2565225ms [junit4] 2> 2562198 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=34743, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/control/cores} [junit4] 2> 2562199 ERROR (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2562199 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 2562199 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2562199 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2562199 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-06-12T19:44:29.713Z [junit4] 2> 2562199 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/control/solr.xml [junit4] 2> 2562218 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 2562263 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c [junit4] 2> 2562268 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c [junit4] 2> 2562268 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c [junit4] 2> 2562270 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/control/cores [junit4] 2> 2562270 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2562271 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 2562313 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 2562337 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2562440 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 2562445 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 2562445 INFO (coreLoadExecutor-3750-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@7d23d27c [junit4] 2> 2562445 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2562446 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 2562447 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=7, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2562505 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2562505 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2562506 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2562506 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2562507 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=60.5263671875, floorSegmentMB=1.130859375, forceMergeDeletesPctAllowed=1.8812980484576602, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 2562508 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@47029ba6[collection1] main] [junit4] 2> 2562508 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 2562517 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 2562543 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2562543 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 2562543 INFO (coreLoadExecutor-3750-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570029353101688832 [junit4] 2> 2562544 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 2562544 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 2562544 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 2562544 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 2562544 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 2562544 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 2562544 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 2562545 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 2562546 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@47029ba6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2562907 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 2562909 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 2562913 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@114d3652{/,null,AVAILABLE} [junit4] 2> 2562913 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3a4c461d{HTTP/1.1,[http/1.1]}{127.0.0.1:60942} [junit4] 2> 2562914 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.e.j.s.Server Started @2565941ms [junit4] 2> 2562914 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=60942, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/shard0/cores} [junit4] 2> 2562914 ERROR (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2562914 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0 [junit4] 2> 2562915 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 2562915 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2562915 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-06-12T19:44:30.429Z [junit4] 2> 2562915 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/shard0/solr.xml [junit4] 2> 2562920 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 2562955 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c [junit4] 2> 2562961 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c [junit4] 2> 2562961 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c [junit4] 2> 2562962 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/shard0/cores [junit4] 2> 2562963 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 2562963 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 2562995 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 2563018 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2563120 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 2563125 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 2563126 INFO (coreLoadExecutor-3761-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@7d23d27c [junit4] 2> 2563126 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2563126 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 2563127 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=7, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2563188 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 2563188 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2563189 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2563189 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2563190 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=60.5263671875, floorSegmentMB=1.130859375, forceMergeDeletesPctAllowed=1.8812980484576602, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 2563191 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e850bca[collection1] main] [junit4] 2> 2563191 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 2563202 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 2563228 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2563228 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 2563228 INFO (coreLoadExecutor-3761-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570029353819963392 [junit4] 2> 2563228 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 2563228 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 2563228 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 2563229 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 2563229 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 2563229 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 2563229 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 2563230 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 2563231 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e850bca[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2563488 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 2563491 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1570029354093641728)} 0 1 [junit4] 2> 2563493 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1570029354096787456)} 0 1 [junit4] 2> 2563495 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1570029354098884608)]} 0 1 [junit4] 2> 2563497 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1570029354100981760)]} 0 1 [junit4] 2> 2563498 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1570029354102030336)]} 0 0 [junit4] 2> 2563499 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1570029354104127488)]} 0 0 [junit4] 2> 2563501 INFO (qtp494089468-13187) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1570029354105176064)]} 0 1 [junit4] 2> 2563502 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1570029354107273216)]} 0 0 [junit4] 2> 2563503 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1570029354108321792)]} 0 0 [junit4] 2> 2563504 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1570029354109370368)]} 0 0 [junit4] 2> 2563505 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1570029354110418944)]} 0 0 [junit4] 2> 2563506 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1570029354111467520)]} 0 0 [junit4] 2> 2563507 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1570029354112516096)]} 0 0 [junit4] 2> 2563509 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1570029354114613248)]} 0 0 [junit4] 2> 2563510 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1570029354115661824)]} 0 0 [junit4] 2> 2563511 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1570029354115661824)]} 0 0 [junit4] 2> 2563512 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1570029354117758976)]} 0 0 [junit4] 2> 2563513 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1570029354118807552)]} 0 0 [junit4] 2> 2563514 INFO (qtp494089468-13187) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1570029354119856128)]} 0 0 [junit4] 2> 2563515 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1570029354119856128)]} 0 0 [junit4] 2> 2563515 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570029354120904704,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2563515 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6b662ce2 commitCommandVersion:1570029354120904704 [junit4] 2> 2563535 INFO (qtp494089468-13188) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5185a276[collection1] main] [junit4] 2> 2563538 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5185a276[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))} [junit4] 2> 2563538 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2563538 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 22 [junit4] 2> 2563539 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570029354146070528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2563539 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3d24057f commitCommandVersion:1570029354146070528 [junit4] 2> 2563547 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@682c3d6f[collection1] main] [junit4] 2> 2563549 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@682c3d6f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))} [junit4] 2> 2563549 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2563549 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 10 [junit4] 2> 2563552 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1570029354157604864)]} 0 2 [junit4] 2> 2563555 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1570029354160750592)]} 0 1 [junit4] 2> 2563556 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1570029354162847744)]} 0 0 [junit4] 2> 2563557 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1570029354163896320)]} 0 0 [junit4] 2> 2563558 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1570029354164944896)]} 0 0 [junit4] 2> 2563558 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1570029354165993472)]} 0 0 [junit4] 2> 2563559 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1570029354167042048)]} 0 0 [junit4] 2> 2563560 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1570029354167042048)]} 0 0 [junit4] 2> 2563561 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1570029354168090624)]} 0 0 [junit4] 2> 2563562 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1570029354169139200)]} 0 0 [junit4] 2> 2563563 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1570029354170187776)]} 0 0 [junit4] 2> 2563564 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1570029354171236352)]} 0 0 [junit4] 2> 2563564 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1570029354172284928)]} 0 0 [junit4] 2> 2563565 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1570029354173333504)]} 0 0 [junit4] 2> 2563566 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1570029354173333504)]} 0 0 [junit4] 2> 2563567 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1570029354174382080)]} 0 0 [junit4] 2> 2563568 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1570029354175430656)]} 0 0 [junit4] 2> 2563569 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1570029354176479232)]} 0 0 [junit4] 2> 2563570 INFO (qtp494089468-13187) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1570029354177527808)]} 0 0 [junit4] 2> 2563571 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1570029354178576384)]} 0 0 [junit4] 2> 2563571 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1570029354179624960)]} 0 0 [junit4] 2> 2563572 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1570029354180673536)]} 0 0 [junit4] 2> 2563573 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1570029354180673536)]} 0 0 [junit4] 2> 2563574 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1570029354181722112)]} 0 0 [junit4] 2> 2563575 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1570029354182770688)]} 0 0 [junit4] 2> 2563575 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1570029354183819264)]} 0 0 [junit4] 2> 2563576 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1570029354184867840)]} 0 0 [junit4] 2> 2563577 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1570029354185916416)]} 0 0 [junit4] 2> 2563578 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1570029354186964992)]} 0 0 [junit4] 2> 2563579 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1570029354186964992)]} 0 0 [junit4] 2> 2563579 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1570029354188013568)]} 0 0 [junit4] 2> 2563580 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1570029354189062144)]} 0 0 [junit4] 2> 2563581 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1570029354190110720)]} 0 0 [junit4] 2> 2563582 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1570029354190110720)]} 0 0 [junit4] 2> 2563582 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1570029354191159296)]} 0 0 [junit4] 2> 2563583 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1570029354192207872)]} 0 0 [junit4] 2> 2563584 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1570029354193256448)]} 0 0 [junit4] 2> 2563585 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1570029354193256448)]} 0 0 [junit4] 2> 2563586 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1570029354194305024)]} 0 0 [junit4] 2> 2563586 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1570029354195353600)]} 0 0 [junit4] 2> 2563587 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1570029354196402176)]} 0 0 [junit4] 2> 2563588 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1570029354196402176)]} 0 0 [junit4] 2> 2563588 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1570029354197450752)]} 0 0 [junit4] 2> 2563589 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1570029354198499328)]} 0 0 [junit4] 2> 2563590 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1570029354198499328)]} 0 0 [junit4] 2> 2563590 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1570029354199547904)]} 0 0 [junit4] 2> 2563591 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1570029354200596480)]} 0 0 [junit4] 2> 2563592 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1570029354201645056)]} 0 0 [junit4] 2> 2563593 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1570029354201645056)]} 0 0 [junit4] 2> 2563593 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1570029354202693632)]} 0 0 [junit4] 2> 2563594 INFO (qtp494089468-13187) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1570029354203742208)]} 0 0 [junit4] 2> 2563595 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1570029354203742208)]} 0 0 [junit4] 2> 2563595 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1570029354204790784)]} 0 0 [junit4] 2> 2563596 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1570029354205839360)]} 0 0 [junit4] 2> 2563597 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1570029354205839360)]} 0 0 [junit4] 2> 2563597 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1570029354206887936)]} 0 0 [junit4] 2> 2563598 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1570029354207936512)]} 0 0 [junit4] 2> 2563599 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1570029354207936512)]} 0 0 [junit4] 2> 2563600 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1570029354208985088)]} 0 0 [junit4] 2> 2563600 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1570029354210033664)]} 0 0 [junit4] 2> 2563601 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1570029354211082240)]} 0 0 [junit4] 2> 2563602 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1570029354211082240)]} 0 0 [junit4] 2> 2563603 INFO (qtp494089468-13187) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1570029354212130816)]} 0 0 [junit4] 2> 2563603 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1570029354213179392)]} 0 0 [junit4] 2> 2563604 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1570029354214227968)]} 0 0 [junit4] 2> 2563605 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1570029354214227968)]} 0 0 [junit4] 2> 2563605 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1570029354215276544)]} 0 0 [junit4] 2> 2563606 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1570029354216325120)]} 0 0 [junit4] 2> 2563607 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1570029354216325120)]} 0 0 [junit4] 2> 2563607 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1570029354217373696)]} 0 0 [junit4] 2> 2563608 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1570029354218422272)]} 0 0 [junit4] 2> 2563609 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1570029354218422272)]} 0 0 [junit4] 2> 2563610 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1570029354219470848)]} 0 0 [junit4] 2> 2563611 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1570029354220519424)]} 0 0 [junit4] 2> 2563611 INFO (qtp494089468-13187) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1570029354221568000)]} 0 0 [junit4] 2> 2563612 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1570029354222616576)]} 0 0 [junit4] 2> 2563613 INFO (qtp494089468-13187) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1570029354223665152)]} 0 0 [junit4] 2> 2563614 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1570029354223665152)]} 0 0 [junit4] 2> 2563614 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1570029354224713728)]} 0 0 [junit4] 2> 2563615 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1570029354225762304)]} 0 0 [junit4] 2> 2563616 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1570029354225762304)]} 0 0 [junit4] 2> 2563616 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1570029354226810880)]} 0 0 [junit4] 2> 2563617 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1570029354227859456)]} 0 0 [junit4] 2> 2563624 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1570029354235199488)]} 0 0 [junit4] 2> 2563625 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1570029354236248064)]} 0 0 [junit4] 2> 2563626 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1570029354237296640)]} 0 0 [junit4] 2> 2563627 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1570029354237296640)]} 0 0 [junit4] 2> 2563627 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1570029354238345216)]} 0 0 [junit4] 2> 2563628 INFO (qtp494089468-13188) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1570029354239393792)]} 0 0 [junit4] 2> 2563629 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1570029354239393792)]} 0 0 [junit4] 2> 2563629 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1570029354240442368)]} 0 0 [junit4] 2> 2563630 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1570029354241490944)]} 0 0 [junit4] 2> 2563631 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1570029354241490944)]} 0 0 [junit4] 2> 2563631 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1570029354242539520)]} 0 0 [junit4] 2> 2563632 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1570029354243588096)]} 0 0 [junit4] 2> 2563633 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1570029354243588096)]} 0 0 [junit4] 2> 2563633 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1570029354244636672)]} 0 0 [junit4] 2> 2563634 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1570029354245685248)]} 0 0 [junit4] 2> 2563635 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1570029354245685248)]} 0 0 [junit4] 2> 2563635 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1570029354246733824)]} 0 0 [junit4] 2> 2563636 INFO (qtp494089468-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1570029354247782400)]} 0 0 [junit4] 2> 2563637 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1570029354247782400)]} 0 0 [junit4] 2> 2563637 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1570029354248830976)]} 0 0 [junit4] 2> 2563638 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1570029354249879552)]} 0 0 [junit4] 2> 2563639 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1570029354249879552)]} 0 0 [junit4] 2> 2563639 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1570029354250928128)]} 0 0 [junit4] 2> 2563640 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1570029354251976704)]} 0 0 [junit4] 2> 2563641 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1570029354251976704)]} 0 0 [junit4] 2> 2563641 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1570029354253025280)]} 0 0 [junit4] 2> 2563642 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1570029354254073856)]} 0 0 [junit4] 2> 2563643 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1570029354254073856)]} 0 0 [junit4] 2> 2563643 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1570029354255122432)]} 0 0 [junit4] 2> 2563644 INFO (qtp494089468-13181) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1570029354256171008)]} 0 0 [junit4] 2> 2563645 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1570029354256171008)]} 0 0 [junit4] 2> 2563645 INFO (qtp494089468-13186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1570029354257219584)]} 0 0 [junit4] 2> 2563646 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1570029354258268160)]} 0 0 [junit4] 2> 2563647 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1570029354258268160)]} 0 0 [junit4] 2> 2563647 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1570029354259316736)]} 0 0 [junit4] 2> 2563648 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570029354260365312,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2563648 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6b662ce2 commitCommandVersion:1570029354260365312 [junit4] 2> 2563661 INFO (qtp494089468-13185) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@436f7792[collection1] main] [junit4] 2> 2563662 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2563663 INFO (searcherExecutor-3751-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@436f7792[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9) Uninverting(_1(7.0.0):c59)))} [junit4] 2> 2563663 INFO (qtp494089468-13185) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 15 [junit4] 2> 2563664 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1570029354277142528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 2563664 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3d24057f commitCommandVersion:1570029354277142528 [junit4] 2> 2563676 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@25ecef88[collection1] main] [junit4] 2> 2563678 INFO (searcherExecutor-3762-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@25ecef88[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9) Uninverting(_1(7.0.0):c59)))} [junit4] 2> 2563679 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 2563679 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 14 [junit4] 2> 2563680 INFO (qtp494089468-13188) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 2563680 INFO (qtp494089468-13188) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 2563681 INFO (qtp494089468-13188) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 2563681 INFO (qtp494089468-13188) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 2563682 INFO (qtp494089468-13181) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 2563738 INFO (qtp1207983899-13199) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671198&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563743 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671198&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563749 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=64 [junit4] 2> 2563749 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.BaseDistributedSearchTestCase starting stress... [junit4] 2> 2563753 INFO (qtp1207983899-13199) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671266&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563754 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671267&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563754 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671268&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563755 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671268&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563756 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671267&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563757 INFO (qtp1207983899-13203) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4 [junit4] 2> 2563758 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 2563759 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671266&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563760 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671272&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563761 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=8 [junit4] 2> 2563763 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671272&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563763 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671273&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563764 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671276&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563764 INFO (qtp1207983899-13199) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6 [junit4] 2> 2563765 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671273&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563766 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6 [junit4] 2> 2563767 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671276&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563768 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6 [junit4] 2> 2563768 INFO (qtp494089468-13186) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 2563796 INFO (qtp1207983899-13224) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671284&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563798 INFO (qtp1207983899-13199) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671284&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563806 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=36 [junit4] 2> 2563807 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.BaseDistributedSearchTestCase starting stress... [junit4] 2> 2563808 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671321&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563808 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671321&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563809 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671321&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563809 INFO (qtp1207983899-13225) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671323&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563810 INFO (qtp1207983899-13225) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671321&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563811 INFO (qtp1207983899-13227) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 2563811 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 2563812 INFO (qtp1207983899-13224) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671323&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563813 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4 [junit4] 2> 2563816 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671327&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563817 INFO (qtp1207983899-13225) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671330&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563818 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671331&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563818 INFO (qtp1207983899-13225) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671327&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563818 INFO (qtp1207983899-13199) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5 [junit4] 2> 2563819 INFO (qtp1207983899-13225) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671331&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563820 INFO (qtp1207983899-13199) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671330&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563820 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 2563820 INFO (qtp1207983899-13205) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4 [junit4] 2> 2563822 INFO (qtp494089468-13187) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 2563824 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671337&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563825 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671337&ids=132,143,133,134,15,4,5,138,107,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563826 INFO (qtp1207983899-13201) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 2563827 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.a.s.BaseDistributedSearchTestCase starting stress... [junit4] 2> 2563828 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671341&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563829 INFO (qtp1207983899-13202) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671341&ids=132,143,133,134,15,4,5,138,107,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563829 INFO (qtp1207983899-13227) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 2563830 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671344&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563831 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671344&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 2563831 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671344&ids=132,143,133,134,15,4,5,138,107,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563832 INFO (qtp1207983899-13198) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671344&ids=132,143,133,134,15,4,5,138,107,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2563833 INFO (qtp1207983899-13225) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 2563833 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 2563834 INFO (qtp1207983899-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671347&isShard=true&wt=javab [...truncated too long message...] ection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1315432679 [junit4] 2> 2701821 INFO (TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@24b02c83{/,null,UNAVAILABLE} [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=96F460804B43D487 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=en-ZA -Dtests.timezone=America/Merida -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 140s J0 | TestDistributedSearch.test <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=44906, name=Thread-33143, state=RUNNABLE, group=TGRP-TestDistributedSearch] [junit4] > at __randomizedtesting.SeedInfo.seed([96F460804B43D487:1EA05F5AE5BFB97F]:0) [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39331//collection1: java.lang.NullPointerException [junit4] > at org.apache.solr.search.grouping.distributed.responseprocessor.StoredFieldsShardResponseProcessor.process(StoredFieldsShardResponseProcessor.java:41) [junit4] > at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:768) [junit4] > at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:751) [junit4] > at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:423) [junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178) [junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2487) [junit4] > at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722) [junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374) [junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318) [junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699) [junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] > at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462) [junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:534) [junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [junit4] > at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273) [junit4] > at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [junit4] > at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] > at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([96F460804B43D487]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:610) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:237) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942) [junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957) [junit4] > at org.apache.solr.TestDistributedSearch$1.run(TestDistributedSearch.java:1140) [junit4] 2> 2701826 INFO (SUITE-TestDistributedSearch-seed#[96F460804B43D487]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {other_tl1=PostingsFormat(name=Memory), range_facet_l_dv=FSTOrd50, SubjectTerms_mfacet=PostingsFormat(name=Memory), n_l1=PostingsFormat(name=Memory), a_n_tdt=FSTOrd50, multiDefault=BlockTreeOrds(blocksize=128), intDefault=PostingsFormat(name=Memory), n_td1=BlockTreeOrds(blocksize=128), n_d1=PostingsFormat(name=Memory), n_f1=BlockTreeOrds(blocksize=128), range_facet_l=PostingsFormat(name=Memory), n_tl1=BlockTreeOrds(blocksize=128), a_s=PostingsFormat(name=LuceneFixedGap), oddField_s=PostingsFormat(name=LuceneFixedGap), n_tf1=PostingsFormat(name=Memory), lowerfilt1and2=BlockTreeOrds(blocksize=128), a_t=PostingsFormat(name=Memory), lowerfilt=BlockTreeOrds(blocksize=128), id=FSTOrd50, text=PostingsFormat(name=LuceneFixedGap), timestamp=PostingsFormat(name=Memory), severity=PostingsFormat(name=LuceneFixedGap), foo_sev_enum=FSTOrd50, n_dt1=BlockTreeOrds(blocksize=128), b_n_tdt=PostingsFormat(name=Memory), n_ti1=FSTOrd50, rnd_b=FSTOrd50, foo_b=PostingsFormat(name=LuceneFixedGap), n_tdt1=PostingsFormat(name=Memory), foo_d=FSTOrd50, range_facet_i_dv=PostingsFormat(name=Memory), foo_f=PostingsFormat(name=LuceneFixedGap)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), foo_sev_enum=DocValuesFormat(name=Memory), _version_=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Asserting), a_i_p=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1808, maxMBSortInHeap=5.238133884416585, sim=RandomSimilarity(queryNorm=false): {}, locale=en-ZA, timezone=America/Merida [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=4,threads=2,free=154826872,total=534773760 [junit4] 2> NOTE: All tests run in this JVM: [TestCrossCoreJoin, RAMDirectoryFactoryTest, QueryElevationComponentTest, ConnectionReuseTest, TestHttpShardHandlerFactory, StandardRequestHandlerTest, TimeZoneUtilsTest, TestFunctionQuery, CopyFieldTest, QueryEqualityTest, LeaderElectionIntegrationTest, HLLUtilTest, TestBackupRepositoryFactory, CurrencyFieldOpenExchangeTest, TestMaxScoreQueryParser, ParsingFieldUpdateProcessorsTest, DistributedVersionInfoTest, DocumentAnalysisRequestHandlerTest, TestCodecSupport, TestSmileRequest, TestReplicationHandler, TestFaceting, DistributedQueryComponentCustomSortTest, TestChildDocTransformer, SolrMetricsIntegrationTest, TestFoldingMultitermQuery, SecurityConfHandlerTest, CdcrRequestHandlerTest, TestCopyFieldCollectionResource, AnalysisAfterCoreReloadTest, TestIntervalFaceting, TestFieldCacheSort, BlobRepositoryCloudTest, ReturnFieldsTest, ShufflingReplicaListTransformerTest, TestFieldCacheWithThreads, DistributedFacetExistsSmallTest, DistributedQueryElevationComponentTest, UUIDFieldTest, TestAnalyzedSuggestions, JavabinLoaderTest, TestCoreAdminApis, DisMaxRequestHandlerTest, TestCollectionAPI, TestFieldSortValues, SpatialRPTFieldTypeTest, DistribDocExpirationUpdateProcessorTest, LeaderFailureAfterFreshStartTest, BasicDistributedZkTest, TestSQLHandlerNonCloud, PreAnalyzedFieldTest, InfoHandlerTest, TestHighFrequencyDictionaryFactory, CollectionTooManyReplicasTest, CloudMLTQParserTest, TestRealTimeGet, TestJsonFacetRefinement, TestApiFramework, ConfigureRecoveryStrategyTest, TestCSVLoader, TestDistributedSearch] [junit4] Completed [442/725 (1!)] on J0 in 139.93s, 1 test, 1 error <<< FAILURES! [...truncated 8420 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:783: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:727: Some of the tests produced a heap dump, but did not fail. Maybe a suppressed OutOfMemoryError? Dumps created: * java_pid30482.hprof Total time: 292 minutes 22 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org