Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21665/ Java: 64bit/jdk-10-ea+43 -XX:+UseCompressedOops -XX:+UseParallelGC
2 tests failed. FAILED: org.apache.solr.TestDistributedSearch.test Error Message: IOException occured when talking to server at: http://127.0.0.1:39607//collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:39607//collection1 at __randomizedtesting.SeedInfo.seed([7E9621BF083B5D15:F6C21E65A6C730ED]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:657) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:895) at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:858) at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:873) at org.apache.solr.BaseDistributedSearchTestCase.del(BaseDistributedSearchTestCase.java:542) at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:1034) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:39607 failed to respond at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:141) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542) ... 48 more FAILED: org.apache.solr.TestDistributedSearch.test Error Message: IOException occured when talking to server at: http://127.0.0.1:36423//collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:36423//collection1 at __randomizedtesting.SeedInfo.seed([7E9621BF083B5D15:F6C21E65A6C730ED]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:657) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:895) at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:858) at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:873) at org.apache.solr.BaseDistributedSearchTestCase.del(BaseDistributedSearchTestCase.java:542) at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:1034) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:36423 failed to respond at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:141) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542) ... 48 more Build Log: [...truncated 1846 lines...] [junit4] JVM J0: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J0-20180319_083126_6364436670670918063193.sysout [junit4] >>> JVM J0 emitted unexpected output (verbatim) ---- [junit4] codec: FastDecompressionCompressingStoredFields, pf: TestBloomFilteredLucenePostings, dvf: Direct [junit4] <<< JVM J0: EOF ---- [...truncated 11533 lines...] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] 2> 904031 INFO (SUITE-TestDistributedSearch-seed#[7E9621BF083B5D15]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001/init-core-data-001 [junit4] 2> 904031 WARN (SUITE-TestDistributedSearch-seed#[7E9621BF083B5D15]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=877 numCloses=877 [junit4] 2> 904031 INFO (SUITE-TestDistributedSearch-seed#[7E9621BF083B5D15]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 904031 INFO (SUITE-TestDistributedSearch-seed#[7E9621BF083B5D15]-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> 904032 INFO (SUITE-TestDistributedSearch-seed#[7E9621BF083B5D15]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 904089 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001/tempDir-001/control/cores/collection1 [junit4] 2> 904091 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 904092 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 904092 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 904092 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 904092 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2be9f8fd{/,null,AVAILABLE} [junit4] 2> 904093 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4f17ac5{HTTP/1.1,[http/1.1]}{127.0.0.1:42193} [junit4] 2> 904093 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.Server Started @905614ms [junit4] 2> 904093 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=42193, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001/tempDir-001/control/cores} [junit4] 2> 904093 ERROR (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 904093 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 904093 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 904093 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 904093 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-19T09:07:53.560626Z [junit4] 2> 904093 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001/tempDir-001/control/solr.xml [junit4] 2> 904095 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 904095 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 904095 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1ea00d65, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 904114 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ea00d65 [junit4] 2> 904120 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ea00d65 [junit4] 2> 904120 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ea00d65 [junit4] 2> 904121 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001/tempDir-001/control/cores [junit4] 2> 904121 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 904121 INFO (coreLoadExecutor-5654-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 904121 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 904138 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 904153 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 904193 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 904202 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 904202 INFO (coreLoadExecutor-5654-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@1ea00d65 [junit4] 2> 904202 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 904202 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 904203 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=523725179, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 904230 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 904230 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 904231 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 904231 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 904231 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=92.20703125, floorSegmentMB=0.3125, forceMergeDeletesPctAllowed=24.865160909817277, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.35853367979903783 [junit4] 2> 904232 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@401b9b4c[collection1] main] [junit4] 2> 904232 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 904253 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 904336 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 904337 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 904337 INFO (coreLoadExecutor-5654-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1595356452019503104 [junit4] 2> 904337 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 904337 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 904337 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 904337 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 904337 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 904337 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 904337 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 904337 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 904338 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@401b9b4c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 904398 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 904398 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 904399 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 904399 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 904399 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 904400 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f249c7a{/,null,AVAILABLE} [junit4] 2> 904400 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@145b6200{HTTP/1.1,[http/1.1]}{127.0.0.1:34433} [junit4] 2> 904400 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.Server Started @905921ms [junit4] 2> 904400 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=34433, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001/tempDir-001/shard0/cores} [junit4] 2> 904400 ERROR (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 904400 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 904400 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 904400 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 904400 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-19T09:07:53.867619Z [junit4] 2> 904400 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001/tempDir-001/shard0/solr.xml [junit4] 2> 904401 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 904401 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 904402 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1ea00d65, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 904419 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ea00d65 [junit4] 2> 904425 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ea00d65 [junit4] 2> 904425 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ea00d65 [junit4] 2> 904426 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001/tempDir-001/shard0/cores [junit4] 2> 904426 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 904426 INFO (coreLoadExecutor-5665-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 904426 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 904438 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 904454 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 904504 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 904510 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 904511 INFO (coreLoadExecutor-5665-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@1ea00d65 [junit4] 2> 904511 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 904511 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 904512 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=523725179, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 904543 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 904543 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 904544 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 904544 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 904545 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=92.20703125, floorSegmentMB=0.3125, forceMergeDeletesPctAllowed=24.865160909817277, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.35853367979903783 [junit4] 2> 904545 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@74fec8ba[collection1] main] [junit4] 2> 904546 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 904567 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 904646 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 904647 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 904647 INFO (coreLoadExecutor-5665-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1595356452344561664 [junit4] 2> 904647 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 904647 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 904647 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 904647 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 904647 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 904647 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 904647 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 904648 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 904648 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@74fec8ba[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 904649 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 904651 INFO (qtp165114658-13243) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1595356452347707392)} 0 1 [junit4] 2> 904652 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1595356452349804544)} 0 0 [junit4] 2> 904654 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1595356452350853120)]} 0 1 [junit4] 2> 904654 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1595356452351901696)]} 0 0 [junit4] 2> 904655 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1595356452352950272)]} 0 0 [junit4] 2> 904656 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1595356452352950272)]} 0 0 [junit4] 2> 904656 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1595356452353998848)]} 0 0 [junit4] 2> 904656 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1595356452353998848)]} 0 0 [junit4] 2> 904657 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1595356452355047424)]} 0 0 [junit4] 2> 904657 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1595356452355047424)]} 0 0 [junit4] 2> 904658 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1595356452356096000)]} 0 0 [junit4] 2> 904658 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1595356452356096000)]} 0 0 [junit4] 2> 904659 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1595356452356096001)]} 0 0 [junit4] 2> 904659 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1595356452357144576)]} 0 0 [junit4] 2> 904659 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1595356452357144576)]} 0 0 [junit4] 2> 904660 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1595356452358193152)]} 0 0 [junit4] 2> 904660 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1595356452358193152)]} 0 0 [junit4] 2> 904661 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1595356452358193153)]} 0 0 [junit4] 2> 904661 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1595356452359241728)]} 0 0 [junit4] 2> 904661 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1595356452359241728)]} 0 0 [junit4] 2> 904661 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1595356452359241729,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 904661 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1f1b7f6b commitCommandVersion:1595356452359241729 [junit4] 2> 904667 INFO (qtp165114658-13249) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4513a058[collection1] main] [junit4] 2> 904667 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 904668 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4513a058[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)))} [junit4] 2> 904668 INFO (qtp165114658-13249) [ 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 6 [junit4] 2> 904668 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1595356452366581760,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 904669 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@248d8f44 commitCommandVersion:1595356452366581760 [junit4] 2> 904673 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@29aae707[collection1] main] [junit4] 2> 904673 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 904674 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@29aae707[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)))} [junit4] 2> 904674 INFO (qtp1927563360-13266) [ 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 5 [junit4] 2> 904675 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1595356452373921792)]} 0 0 [junit4] 2> 904676 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1595356452374970368)]} 0 0 [junit4] 2> 904677 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1595356452374970368)]} 0 0 [junit4] 2> 904678 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1595356452376018944)]} 0 0 [junit4] 2> 904678 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1595356452377067520)]} 0 0 [junit4] 2> 904678 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1595356452377067520)]} 0 0 [junit4] 2> 904679 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1595356452378116096)]} 0 0 [junit4] 2> 904679 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1595356452378116096)]} 0 0 [junit4] 2> 904680 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1595356452378116097)]} 0 0 [junit4] 2> 904681 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1595356452379164672)]} 0 0 [junit4] 2> 904681 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1595356452380213248)]} 0 0 [junit4] 2> 904681 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1595356452380213248)]} 0 0 [junit4] 2> 904682 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1595356452381261824)]} 0 0 [junit4] 2> 904682 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1595356452381261824)]} 0 0 [junit4] 2> 904682 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1595356452381261825)]} 0 0 [junit4] 2> 904683 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1595356452382310400)]} 0 0 [junit4] 2> 904683 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1595356452382310400)]} 0 0 [junit4] 2> 904684 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1595356452382310401)]} 0 0 [junit4] 2> 904684 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1595356452383358976)]} 0 0 [junit4] 2> 904684 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1595356452383358976)]} 0 0 [junit4] 2> 904685 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1595356452383358977)]} 0 0 [junit4] 2> 904685 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1595356452384407552)]} 0 0 [junit4] 2> 904685 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1595356452384407552)]} 0 0 [junit4] 2> 904686 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1595356452384407553)]} 0 0 [junit4] 2> 904686 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1595356452385456128)]} 0 0 [junit4] 2> 904686 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1595356452385456128)]} 0 0 [junit4] 2> 904686 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1595356452385456129)]} 0 0 [junit4] 2> 904687 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1595356452386504704)]} 0 0 [junit4] 2> 904687 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1595356452386504704)]} 0 0 [junit4] 2> 904687 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1595356452386504705)]} 0 0 [junit4] 2> 904688 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1595356452386504705)]} 0 0 [junit4] 2> 904688 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1595356452387553280)]} 0 0 [junit4] 2> 904688 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1595356452387553280)]} 0 0 [junit4] 2> 904688 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1595356452387553281)]} 0 0 [junit4] 2> 904689 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1595356452388601856)]} 0 0 [junit4] 2> 904689 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1595356452388601856)]} 0 0 [junit4] 2> 904689 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1595356452388601857)]} 0 0 [junit4] 2> 904689 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1595356452388601857)]} 0 0 [junit4] 2> 904690 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1595356452389650432)]} 0 0 [junit4] 2> 904690 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1595356452389650432)]} 0 0 [junit4] 2> 904690 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1595356452389650433)]} 0 0 [junit4] 2> 904691 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1595356452390699008)]} 0 0 [junit4] 2> 904691 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1595356452390699008)]} 0 0 [junit4] 2> 904691 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1595356452390699009)]} 0 0 [junit4] 2> 904691 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1595356452390699009)]} 0 0 [junit4] 2> 904692 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1595356452391747584)]} 0 0 [junit4] 2> 904692 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1595356452391747584)]} 0 0 [junit4] 2> 904692 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1595356452391747585)]} 0 0 [junit4] 2> 904692 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1595356452391747585)]} 0 0 [junit4] 2> 904693 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1595356452392796160)]} 0 0 [junit4] 2> 904693 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1595356452392796160)]} 0 0 [junit4] 2> 904693 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1595356452392796161)]} 0 0 [junit4] 2> 904694 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1595356452393844736)]} 0 0 [junit4] 2> 904694 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1595356452393844736)]} 0 0 [junit4] 2> 904694 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1595356452393844737)]} 0 0 [junit4] 2> 904695 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1595356452394893312)]} 0 0 [junit4] 2> 904695 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1595356452394893312)]} 0 0 [junit4] 2> 904696 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1595356452394893313)]} 0 0 [junit4] 2> 904696 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1595356452395941888)]} 0 0 [junit4] 2> 904696 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1595356452395941888)]} 0 0 [junit4] 2> 904696 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1595356452395941889)]} 0 0 [junit4] 2> 904697 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1595356452396990464)]} 0 0 [junit4] 2> 904697 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1595356452396990464)]} 0 0 [junit4] 2> 904697 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1595356452396990465)]} 0 0 [junit4] 2> 904698 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1595356452398039040)]} 0 0 [junit4] 2> 904698 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1595356452398039040)]} 0 0 [junit4] 2> 904698 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1595356452398039041)]} 0 0 [junit4] 2> 904698 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1595356452398039041)]} 0 0 [junit4] 2> 904699 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1595356452399087616)]} 0 0 [junit4] 2> 904699 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1595356452399087616)]} 0 0 [junit4] 2> 904700 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1595356452399087617)]} 0 0 [junit4] 2> 904700 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1595356452400136192)]} 0 0 [junit4] 2> 904700 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1595356452400136192)]} 0 0 [junit4] 2> 904701 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1595356452400136193)]} 0 0 [junit4] 2> 904701 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1595356452401184768)]} 0 0 [junit4] 2> 904701 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1595356452401184768)]} 0 0 [junit4] 2> 904701 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1595356452401184769)]} 0 0 [junit4] 2> 904702 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1595356452402233344)]} 0 0 [junit4] 2> 904702 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1595356452402233344)]} 0 0 [junit4] 2> 904702 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1595356452402233345)]} 0 0 [junit4] 2> 904703 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1595356452403281920)]} 0 0 [junit4] 2> 904703 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1595356452403281920)]} 0 0 [junit4] 2> 904703 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1595356452403281921)]} 0 0 [junit4] 2> 904704 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1595356452404330496)]} 0 0 [junit4] 2> 904704 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1595356452404330496)]} 0 0 [junit4] 2> 904704 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1595356452404330497)]} 0 0 [junit4] 2> 904705 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1595356452404330497)]} 0 0 [junit4] 2> 904705 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1595356452405379072)]} 0 0 [junit4] 2> 904705 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1595356452405379072)]} 0 0 [junit4] 2> 904705 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1595356452405379073)]} 0 0 [junit4] 2> 904706 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1595356452406427648)]} 0 0 [junit4] 2> 904706 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1595356452406427648)]} 0 0 [junit4] 2> 904706 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1595356452406427649)]} 0 0 [junit4] 2> 904706 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1595356452406427649)]} 0 0 [junit4] 2> 904707 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1595356452407476224)]} 0 0 [junit4] 2> 904707 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1595356452407476224)]} 0 0 [junit4] 2> 904707 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1595356452407476225)]} 0 0 [junit4] 2> 904709 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1595356452409573376)]} 0 0 [junit4] 2> 904709 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1595356452409573376)]} 0 0 [junit4] 2> 904710 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1595356452409573377)]} 0 0 [junit4] 2> 904710 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1595356452410621952)]} 0 0 [junit4] 2> 904710 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1595356452410621952)]} 0 0 [junit4] 2> 904711 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1595356452411670528)]} 0 0 [junit4] 2> 904711 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1595356452411670528)]} 0 0 [junit4] 2> 904711 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1595356452411670529)]} 0 0 [junit4] 2> 904711 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1595356452411670529)]} 0 0 [junit4] 2> 904712 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1595356452412719104)]} 0 0 [junit4] 2> 904712 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1595356452412719104)]} 0 0 [junit4] 2> 904712 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1595356452412719105)]} 0 0 [junit4] 2> 904713 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1595356452412719105)]} 0 0 [junit4] 2> 904713 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1595356452413767680)]} 0 0 [junit4] 2> 904713 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1595356452413767680)]} 0 0 [junit4] 2> 904713 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1595356452413767681)]} 0 0 [junit4] 2> 904714 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1595356452414816256)]} 0 0 [junit4] 2> 904714 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1595356452414816256)]} 0 0 [junit4] 2> 904714 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1595356452414816257)]} 0 0 [junit4] 2> 904714 INFO (qtp165114658-13247) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1595356452414816257)]} 0 0 [junit4] 2> 904715 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1595356452415864832)]} 0 0 [junit4] 2> 904715 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1595356452415864832,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 904715 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1f1b7f6b commitCommandVersion:1595356452415864832 [junit4] 2> 904719 INFO (qtp165114658-13249) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@44aa1c46[collection1] main] [junit4] 2> 904720 INFO (qtp165114658-13249) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 904721 INFO (searcherExecutor-5655-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@44aa1c46[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9) Uninverting(_1(8.0.0):c59)))} [junit4] 2> 904721 INFO (qtp165114658-13249) [ 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 6 [junit4] 2> 904722 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1595356452423204864,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 904722 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@248d8f44 commitCommandVersion:1595356452423204864 [junit4] 2> 904726 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6cb7894c[collection1] main] [junit4] 2> 904726 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 904727 INFO (searcherExecutor-5666-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6cb7894c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9) Uninverting(_1(8.0.0):c59)))} [junit4] 2> 904727 INFO (qtp1927563360-13266) [ 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 5 [junit4] 2> 904728 INFO (qtp165114658-13247) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 904728 INFO (qtp165114658-13247) [ 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> 904729 INFO (qtp165114658-13249) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 904729 INFO (qtp165114658-13249) [ 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> 904729 INFO (qtp165114658-13247) [ 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> 904731 INFO (qtp1927563360-13265) [ 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=http://127.0.0.1:34433//collection1&rows=10&version=2&q=*:*&NOW=1521450474197&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904732 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474197&ids=122,13,123,3,16,17,149,106,119,109&isShard=true&shard.url=http://127.0.0.1:34433//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904735 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:34433//collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5 [junit4] 2> 904736 INFO (qtp165114658-13249) [ 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> 904739 INFO (qtp1927563360-13265) [ 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::083]:33332/|http://127.0.0.1:34433//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1521450474204&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904740 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474204&ids=100,102,136,148,137,126,127,138,105,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:34433//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904742 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:34433//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5 [junit4] 2> 904743 INFO (qtp165114658-13247) [ 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> 904744 INFO (qtp1927563360-13265) [ 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=http://127.0.0.1:34433//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1521450474210&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904744 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474210&ids=121,146,102,3,113,103,104,128,120,131&isShard=true&shard.url=http://127.0.0.1:34433//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904746 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:34433//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 904746 INFO (qtp165114658-13249) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904747 INFO (qtp1927563360-13265) [ 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+asc&shard.url=http://127.0.0.1:34433//collection1&rows=10&version=2&q=*:*&NOW=1521450474214&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904748 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474214&ids=143,111,123,101,14,124,106,9,109,130&isShard=true&shard.url=http://127.0.0.1:34433//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904750 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:34433//collection1&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 904751 INFO (qtp165114658-13247) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904752 INFO (qtp1927563360-13265) [ 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_tf1+desc&shard.url=http://127.0.0.1:34433//collection1|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1521450474218&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904752 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474218&ids=11,143,122,113,115,16,17,119,130,131&isShard=true&shard.url=http://127.0.0.1:34433//collection1|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904753 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:34433//collection1|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 904753 INFO (qtp165114658-13249) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904754 INFO (qtp1927563360-13265) [ 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_tf1+asc&shard.url=http://127.0.0.1:34433//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1521450474221&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904755 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474221&ids=110,13,112,145,134,135,124,138,1001,120&isShard=true&shard.url=http://127.0.0.1:34433//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904756 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:34433//collection1|[ff01::083]:33332/&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 904757 INFO (qtp165114658-13247) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904759 INFO (qtp1927563360-13265) [ 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_d1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:34433//collection1&rows=10&version=2&q=*:*&NOW=1521450474224&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904759 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474224&ids=13,146,135,6,1001,117,129,119,9,142&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:34433//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904760 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:34433//collection1&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 904760 INFO (qtp165114658-13249) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904762 INFO (qtp1927563360-13265) [ 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_d1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:34433//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1521450474228&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904763 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474228&ids=122,12,145,113,125,103,16,105,128,130&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:34433//collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904763 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:34433//collection1|[ff01::114]:33332/&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 904764 INFO (qtp165114658-13247) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904765 INFO (qtp1927563360-13265) [ 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_td1+desc&shard.url=http://127.0.0.1:34433//collection1&rows=10&version=2&q=*:*&NOW=1521450474231&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904765 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474231&ids=12,2,13,135,16,115,17,118,107,108&isShard=true&shard.url=http://127.0.0.1:34433//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904766 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:34433//collection1&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 904766 INFO (qtp165114658-13249) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904768 INFO (qtp1927563360-13265) [ 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_td1+asc&shard.url=http://127.0.0.1:34433//collection1&rows=10&version=2&q=*:*&NOW=1521450474234&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904768 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474234&ids=3,146,14,125,104,138,127,8,129,9&isShard=true&shard.url=http://127.0.0.1:34433//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904769 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:34433//collection1&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 904769 INFO (qtp165114658-13247) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904770 INFO (qtp1927563360-13265) [ 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_l1+desc&shard.url=http://127.0.0.1:34433//collection1&rows=10&version=2&q=*:*&NOW=1521450474237&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904771 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474237&ids=12,123,101,3,113,104,8,119,141,131&isShard=true&shard.url=http://127.0.0.1:34433//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904771 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:34433//collection1&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 904771 INFO (qtp165114658-13249) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904772 INFO (qtp1927563360-13265) [ 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_l1+asc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:34433//collection1&rows=10&version=2&q=*:*&NOW=1521450474239&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904773 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474239&ids=143,2,135,147,103,17,149,106,7,118&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:34433//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904774 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:34433//collection1&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 904774 INFO (qtp165114658-13247) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904776 INFO (qtp1927563360-13265) [ 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_tl1+desc&shard.url=http://127.0.0.1:34433//collection1&rows=10&version=2&q=*:*&NOW=1521450474242&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904776 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474242&ids=113,102,103,16,116,7,118,8,130,142&isShard=true&shard.url=http://127.0.0.1:34433//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904777 INFO (qtp1927563360-13264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:34433//collection1&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 904778 INFO (qtp165114658-13249) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904779 INFO (qtp1927563360-13265) [ 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_tl1+asc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:34433//collection1&rows=10&version=2&q=*:*&NOW=1521450474246&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904780 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1521450474246&ids=110,121,101,146,124,4,114,138,117,119&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:34433//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 904780 INFO (qtp1927563360-13266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:34433//collection1&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1 [junit4] 2> 904781 INFO (qtp165114658-13247) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 904782 INFO (qtp1927563360-13265) [ 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_dt1+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:34433//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1521450474249&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 904783 INFO (qtp1927563360-13260) [ x:collection1] o.a.s.c.S.Request [collection1] [...truncated too long message...] e0a{/,null,UNAVAILABLE} [junit4] 2> 90722 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 90723 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2e5c4356{HTTP/1.1,[http/1.1]}{127.0.0.1:39607} [junit4] 2> 90723 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=366766330 [junit4] 2> 90723 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 90723 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 90723 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 90725 INFO (coreCloseExecutor-260-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@77f0d5ca [junit4] 2> 90725 INFO (coreCloseExecutor-260-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2012272074 [junit4] 2> 90727 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@72c330f{/,null,UNAVAILABLE} [junit4] 2> 90727 INFO (TEST-TestDistributedSearch.test-seed#[7E9621BF083B5D15]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=7E9621BF083B5D15 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=en-UG -Dtests.timezone=Asia/Tomsk -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 25.9s J2 | TestDistributedSearch.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:39607//collection1 [junit4] > at __randomizedtesting.SeedInfo.seed([7E9621BF083B5D15:F6C21E65A6C730ED]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:657) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) [junit4] > at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:895) [junit4] > at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:858) [junit4] > at org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:873) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.del(BaseDistributedSearchTestCase.java:542) [junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:1034) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968) [junit4] > at java.base/java.lang.Thread.run(Thread.java:844) [junit4] > Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:39607 failed to respond [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:141) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) [junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) [junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) [junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) [junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272) [junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) [junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) [junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111) [junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542) [junit4] > ... 48 more [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_7E9621BF083B5D15-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {severity=PostingsFormat(name=Asserting), foo_sev_enum=PostingsFormat(name=Asserting), SubjectTerms_mfacet=FSTOrd50, multiDefault=PostingsFormat(name=MockRandom), rnd_b=PostingsFormat(name=LuceneFixedGap), a_s=PostingsFormat(name=Asserting), oddField_s=PostingsFormat(name=LuceneFixedGap), lowerfilt1and2=PostingsFormat(name=MockRandom), a_t=PostingsFormat(name=MockRandom), foo_b=PostingsFormat(name=Asserting), lowerfilt=PostingsFormat(name=MockRandom), id=PostingsFormat(name=LuceneFixedGap), text=PostingsFormat(name=Asserting)}, docValues:{other_tl1=DocValuesFormat(name=Direct), range_facet_l_dv=DocValuesFormat(name=Asserting), n_l1=DocValuesFormat(name=Lucene70), a_n_tdt=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Direct), n_td1=DocValuesFormat(name=Direct), n_d1=DocValuesFormat(name=Lucene70), range_facet_l=DocValuesFormat(name=Lucene70), n_f1=DocValuesFormat(name=Direct), n_tl1=DocValuesFormat(name=Direct), n_tf1=DocValuesFormat(name=Lucene70), intDvoDefault=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Lucene70), severity=DocValuesFormat(name=Lucene70), foo_sev_enum=DocValuesFormat(name=Lucene70), n_dt1=DocValuesFormat(name=Direct), b_n_tdt=DocValuesFormat(name=Direct), n_ti1=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Direct), n_tdt1=DocValuesFormat(name=Direct), id_i1=DocValuesFormat(name=Direct), foo_d=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Lucene70), foo_f=DocValuesFormat(name=Lucene70), a_i_p=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=370, maxMBSortInHeap=6.593422163085237, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@55144f9c), locale=en-UG, timezone=Asia/Tomsk [junit4] 2> NOTE: Linux 4.13.0-36-generic amd64/Oracle Corporation 10 (64-bit)/cpus=8,threads=1,free=281368096,total=519045120 [junit4] 2> NOTE: All tests run in this JVM: [TestDistributedSearch, TestDistributedSearch] [junit4] Completed [5/5 (1!)] on J2 in 25.98s, 1 test, 1 error <<< FAILURES! [...truncated 11 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1561: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1085: There were test failures: 5 suites, 5 tests, 1 error [seed: 7E9621BF083B5D15] Total time: 1 minute 36 seconds [repro] Setting last failure code to 256 [repro] Failures: [repro] 1/5 failed: org.apache.solr.TestDistributedSearch [repro] Exiting with code 256 + mv lucene/build lucene/build.repro + mv solr/build solr/build.repro + mv lucene/build.orig lucene/build + mv solr/build.orig solr/build Archiving artifacts Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac) Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 <Git Blamer> Using GitBlamer to create author and commit information for all warnings. <Git Blamer> GIT_COMMIT=7d9783d20136e30fe50adc438bf8c894af2fb51e, workspace=/var/lib/jenkins/workspace/Lucene-Solr-master-Linux [WARNINGS] Computing warning deltas based on reference build #21664 Recording test results Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
