Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/915/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.TestDistributedSearch.test Error Message: Error from server at http://127.0.0.1:63786//collection1: java.lang.NullPointerException at org.apache.solr.search.grouping.distributed.responseprocessor.StoredFieldsShardResponseProcessor.process(StoredFieldsShardResponseProcessor.java:38) at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:579) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:562) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:426) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2541) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:709) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:515) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:377) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:323) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:724) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) at org.eclipse.jetty.server.Server.handle(Server.java:531) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) at java.lang.Thread.run(Thread.java:748) Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:63786//collection1: java.lang.NullPointerException at org.apache.solr.search.grouping.distributed.responseprocessor.StoredFieldsShardResponseProcessor.process(StoredFieldsShardResponseProcessor.java:38) at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:579) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:562) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:426) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2541) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:709) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:515) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:377) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:323) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:724) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) at org.eclipse.jetty.server.Server.handle(Server.java:531) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([191E2BA4A4E24DE8:914A147E0A1E2010]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) 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.query(SolrClient.java:983) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:998) at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1168) at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1130) at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1742) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1036) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:985) 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:944) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891) 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 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.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13721 lines...] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_191E2BA4A4E24DE8-001/init-core-data-001 [junit4] 2> 1639520 WARN (SUITE-TestDistributedSearch-seed#[191E2BA4A4E24DE8]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2 [junit4] 2> 1639520 INFO (SUITE-TestDistributedSearch-seed#[191E2BA4A4E24DE8]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 1639521 INFO (SUITE-TestDistributedSearch-seed#[191E2BA4A4E24DE8]-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> 1639521 INFO (SUITE-TestDistributedSearch-seed#[191E2BA4A4E24DE8]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1639521 INFO (SUITE-TestDistributedSearch-seed#[191E2BA4A4E24DE8]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1639657 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_191E2BA4A4E24DE8-001/tempDir-001/control/cores/collection1 [junit4] 2> 1639658 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_191-b12 [junit4] 2> 1639659 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1639659 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1639659 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1639659 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@770b948b{/,null,AVAILABLE} [junit4] 2> 1639660 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3606ee6d{HTTP/1.1,[http/1.1]}{127.0.0.1:58148} [junit4] 2> 1639660 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.Server Started @1641295ms [junit4] 2> 1639660 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=58148, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_191E2BA4A4E24DE8-001/tempDir-001/control/cores} [junit4] 2> 1639660 ERROR (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1639660 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1639660 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.7.0 [junit4] 2> 1639660 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1639660 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1639660 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-11-19T02:50:07.162Z [junit4] 2> 1639661 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_191E2BA4A4E24DE8-001/tempDir-001/control/solr.xml [junit4] 2> 1639663 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1639663 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1639664 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@765ac540, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1641195 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1641195 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1641222 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@765ac540 [junit4] 2> 1641229 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@765ac540 [junit4] 2> 1641229 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@765ac540 [junit4] 2> 1641230 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_191E2BA4A4E24DE8-001/tempDir-001/control/cores [junit4] 2> 1641230 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1641232 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 1641257 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0 [junit4] 2> 1641268 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1641328 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1641334 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 1641334 INFO (coreLoadExecutor-6465-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@765ac540 [junit4] 2> 1641334 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_191E2BA4A4E24DE8-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 1641335 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5678106214365235] [junit4] 2> 1641389 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1641389 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1641390 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1641390 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1641390 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=58.7255859375, floorSegmentMB=0.248046875, forceMergeDeletesPctAllowed=3.9264671401522144, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19194724158910834, deletesPctAllowed=25.13183318766606 [junit4] 2> 1641390 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@67efbce[collection1] main] [junit4] 2> 1641391 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 1641394 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1641409 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1641409 INFO (coreLoadExecutor-6465-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1617528942924660736 [junit4] 2> 1641411 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 1641411 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 1641411 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 1641411 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 1641412 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 1641412 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 1641412 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 1641412 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 1641412 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 1641413 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@67efbce[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1642037 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_191E2BA4A4E24DE8-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 1642038 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_191-b12 [junit4] 2> 1642039 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1642039 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1642039 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1642040 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6e0e2358{/,null,AVAILABLE} [junit4] 2> 1642040 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@46a5a152{HTTP/1.1,[http/1.1]}{127.0.0.1:46873} [junit4] 2> 1642040 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.e.j.s.Server Started @1643677ms [junit4] 2> 1642040 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=46873, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_191E2BA4A4E24DE8-001/tempDir-001/shard0/cores} [junit4] 2> 1642040 ERROR (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1642040 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1642040 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.7.0 [junit4] 2> 1642040 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 1642040 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1642040 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-11-19T02:50:09.542Z [junit4] 2> 1642041 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_191E2BA4A4E24DE8-001/tempDir-001/shard0/solr.xml [junit4] 2> 1642043 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 1642043 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 1642043 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@765ac540, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1642801 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1642801 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1642815 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@765ac540 [junit4] 2> 1642823 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@765ac540 [junit4] 2> 1642824 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@765ac540 [junit4] 2> 1642825 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_191E2BA4A4E24DE8-001/tempDir-001/shard0/cores [junit4] 2> 1642825 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1642826 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 1642850 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.7.0 [junit4] 2> 1642880 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1642941 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1642948 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 1642948 INFO (coreLoadExecutor-6479-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@765ac540 [junit4] 2> 1642948 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_191E2BA4A4E24DE8-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 1642949 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5678106214365235] [junit4] 2> 1643008 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1643008 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1643009 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1643009 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1643010 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=58.7255859375, floorSegmentMB=0.248046875, forceMergeDeletesPctAllowed=3.9264671401522144, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19194724158910834, deletesPctAllowed=25.13183318766606 [junit4] 2> 1643010 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@68ff06d4[collection1] main] [junit4] 2> 1643011 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 1643014 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 1643027 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1643027 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 1643027 INFO (coreLoadExecutor-6479-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1617528944621256704 [junit4] 2> 1643028 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 1643028 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 1643028 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 1643028 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 1643028 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 1643029 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 1643029 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 1643029 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 1643030 INFO (TEST-TestDistributedSearch.test-seed#[191E2BA4A4E24DE8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 1643030 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@68ff06d4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1643037 INFO (qtp1206878471-16571) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1617528944625451008)} 0 6 [junit4] 2> 1643045 INFO (qtp1242102013-16590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1617528944633839616)} 0 6 [junit4] 2> 1643058 INFO (qtp1206878471-16566) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1617528944643276800)]} 0 12 [junit4] 2> 1643070 INFO (qtp1242102013-16586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1617528944656908288)]} 0 11 [junit4] 2> 1643072 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1617528944667394048)]} 0 0 [junit4] 2> 1643073 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1617528944668442624)]} 0 0 [junit4] 2> 1643074 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1617528944670539776)]} 0 0 [junit4] 2> 1643075 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1617528944670539776)]} 0 0 [junit4] 2> 1643076 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1617528944671588352)]} 0 0 [junit4] 2> 1643077 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1617528944672636928)]} 0 0 [junit4] 2> 1643077 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1617528944673685504)]} 0 0 [junit4] 2> 1643078 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1617528944674734080)]} 0 0 [junit4] 2> 1643078 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1617528944674734080)]} 0 0 [junit4] 2> 1643079 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1617528944675782656)]} 0 0 [junit4] 2> 1643079 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1617528944675782656)]} 0 0 [junit4] 2> 1643080 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1617528944676831232)]} 0 0 [junit4] 2> 1643081 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1617528944676831232)]} 0 0 [junit4] 2> 1643081 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1617528944677879808)]} 0 0 [junit4] 2> 1643082 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1617528944678928384)]} 0 0 [junit4] 2> 1643082 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1617528944678928384)]} 0 0 [junit4] 2> 1643083 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1617528944679976960,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1643083 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@31225f2b commitCommandVersion:1617528944679976960 [junit4] 2> 1643097 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3e1212cf[collection1] main] [junit4] 2> 1643097 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1643098 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3e1212cf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.7.0):c9)))} [junit4] 2> 1643098 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 15 [junit4] 2> 1643099 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1617528944696754176,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1643099 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7653f107 commitCommandVersion:1617528944696754176 [junit4] 2> 1643117 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d2fce1b[collection1] main] [junit4] 2> 1643117 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1643144 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3d2fce1b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.7.0):c9)))} [junit4] 2> 1643145 INFO (qtp1242102013-16591) [ 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 46 [junit4] 2> 1643151 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1617528944744988672)]} 0 5 [junit4] 2> 1643155 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1617528944752328704)]} 0 3 [junit4] 2> 1643156 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1617528944755474432)]} 0 0 [junit4] 2> 1643156 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1617528944756523008)]} 0 0 [junit4] 2> 1643157 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1617528944757571584)]} 0 0 [junit4] 2> 1643158 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1617528944757571584)]} 0 0 [junit4] 2> 1643158 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1617528944758620160)]} 0 0 [junit4] 2> 1643159 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1617528944758620160)]} 0 0 [junit4] 2> 1643159 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1617528944759668736)]} 0 0 [junit4] 2> 1643160 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1617528944760717312)]} 0 0 [junit4] 2> 1643161 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1617528944760717312)]} 0 0 [junit4] 2> 1643161 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1617528944761765888)]} 0 0 [junit4] 2> 1643161 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1617528944761765888)]} 0 0 [junit4] 2> 1643162 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1617528944762814464)]} 0 0 [junit4] 2> 1643162 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1617528944762814464)]} 0 0 [junit4] 2> 1643162 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1617528944762814465)]} 0 0 [junit4] 2> 1643163 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1617528944763863040)]} 0 0 [junit4] 2> 1643163 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1617528944763863040)]} 0 0 [junit4] 2> 1643164 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1617528944764911616)]} 0 0 [junit4] 2> 1643165 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1617528944764911616)]} 0 0 [junit4] 2> 1643165 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1617528944765960192)]} 0 0 [junit4] 2> 1643166 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1617528944767008768)]} 0 0 [junit4] 2> 1643166 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1617528944767008768)]} 0 0 [junit4] 2> 1643167 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1617528944768057344)]} 0 0 [junit4] 2> 1643167 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1617528944768057344)]} 0 0 [junit4] 2> 1643168 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1617528944769105920)]} 0 0 [junit4] 2> 1643168 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1617528944769105920)]} 0 0 [junit4] 2> 1643169 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1617528944769105921)]} 0 0 [junit4] 2> 1643169 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1617528944770154496)]} 0 0 [junit4] 2> 1643169 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1617528944770154496)]} 0 0 [junit4] 2> 1643170 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1617528944771203072)]} 0 0 [junit4] 2> 1643170 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1617528944771203072)]} 0 0 [junit4] 2> 1643171 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1617528944772251648)]} 0 0 [junit4] 2> 1643172 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1617528944772251648)]} 0 0 [junit4] 2> 1643172 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1617528944773300224)]} 0 0 [junit4] 2> 1643173 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1617528944773300224)]} 0 0 [junit4] 2> 1643173 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1617528944774348800)]} 0 0 [junit4] 2> 1643174 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1617528944774348800)]} 0 0 [junit4] 2> 1643174 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1617528944775397376)]} 0 0 [junit4] 2> 1643175 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1617528944775397376)]} 0 0 [junit4] 2> 1643175 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1617528944776445952)]} 0 0 [junit4] 2> 1643176 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1617528944776445952)]} 0 0 [junit4] 2> 1643176 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1617528944777494528)]} 0 0 [junit4] 2> 1643177 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1617528944777494528)]} 0 0 [junit4] 2> 1643177 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1617528944778543104)]} 0 0 [junit4] 2> 1643178 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1617528944778543104)]} 0 0 [junit4] 2> 1643178 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1617528944779591680)]} 0 0 [junit4] 2> 1643179 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1617528944779591680)]} 0 0 [junit4] 2> 1643179 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1617528944780640256)]} 0 0 [junit4] 2> 1643180 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1617528944780640256)]} 0 0 [junit4] 2> 1643180 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1617528944781688832)]} 0 0 [junit4] 2> 1643181 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1617528944781688832)]} 0 0 [junit4] 2> 1643181 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1617528944782737408)]} 0 0 [junit4] 2> 1643182 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1617528944782737408)]} 0 0 [junit4] 2> 1643182 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1617528944783785984)]} 0 0 [junit4] 2> 1643183 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1617528944783785984)]} 0 0 [junit4] 2> 1643183 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1617528944784834560)]} 0 0 [junit4] 2> 1643184 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1617528944784834560)]} 0 0 [junit4] 2> 1643184 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1617528944785883136)]} 0 0 [junit4] 2> 1643185 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1617528944786931712)]} 0 0 [junit4] 2> 1643185 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1617528944786931712)]} 0 0 [junit4] 2> 1643186 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1617528944787980288)]} 0 0 [junit4] 2> 1643186 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1617528944787980288)]} 0 0 [junit4] 2> 1643186 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1617528944787980289)]} 0 0 [junit4] 2> 1643187 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1617528944789028864)]} 0 0 [junit4] 2> 1643187 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1617528944789028864)]} 0 0 [junit4] 2> 1643188 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1617528944789028865)]} 0 0 [junit4] 2> 1643188 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1617528944790077440)]} 0 0 [junit4] 2> 1643188 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1617528944790077440)]} 0 0 [junit4] 2> 1643189 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1617528944791126016)]} 0 0 [junit4] 2> 1643189 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1617528944791126016)]} 0 0 [junit4] 2> 1643189 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1617528944791126017)]} 0 0 [junit4] 2> 1643189 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1617528944791126017)]} 0 0 [junit4] 2> 1643190 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1617528944792174592)]} 0 0 [junit4] 2> 1643190 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1617528944792174592)]} 0 0 [junit4] 2> 1643190 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1617528944792174593)]} 0 0 [junit4] 2> 1643191 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1617528944793223168)]} 0 0 [junit4] 2> 1643191 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1617528944793223168)]} 0 0 [junit4] 2> 1643191 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1617528944793223169)]} 0 0 [junit4] 2> 1643192 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1617528944794271744)]} 0 0 [junit4] 2> 1643192 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1617528944794271744)]} 0 0 [junit4] 2> 1643192 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1617528944794271745)]} 0 0 [junit4] 2> 1643193 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1617528944795320320)]} 0 0 [junit4] 2> 1643193 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1617528944795320320)]} 0 0 [junit4] 2> 1643194 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1617528944796368896)]} 0 0 [junit4] 2> 1643194 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1617528944796368896)]} 0 0 [junit4] 2> 1643195 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1617528944797417472)]} 0 0 [junit4] 2> 1643195 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1617528944797417472)]} 0 0 [junit4] 2> 1643196 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1617528944798466048)]} 0 0 [junit4] 2> 1643196 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1617528944798466048)]} 0 0 [junit4] 2> 1643197 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1617528944799514624)]} 0 0 [junit4] 2> 1643197 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1617528944799514624)]} 0 0 [junit4] 2> 1643198 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1617528944800563200)]} 0 0 [junit4] 2> 1643198 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1617528944800563200)]} 0 0 [junit4] 2> 1643199 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1617528944801611776)]} 0 0 [junit4] 2> 1643199 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1617528944801611776)]} 0 0 [junit4] 2> 1643200 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1617528944802660352)]} 0 0 [junit4] 2> 1643200 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1617528944802660352)]} 0 0 [junit4] 2> 1643201 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1617528944803708928)]} 0 0 [junit4] 2> 1643202 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1617528944803708928)]} 0 0 [junit4] 2> 1643202 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1617528944804757504)]} 0 0 [junit4] 2> 1643203 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1617528944804757504)]} 0 0 [junit4] 2> 1643203 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1617528944805806080)]} 0 0 [junit4] 2> 1643203 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1617528944805806080)]} 0 0 [junit4] 2> 1643204 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1617528944806854656)]} 0 0 [junit4] 2> 1643205 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1617528944807903232)]} 0 0 [junit4] 2> 1643205 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1617528944807903232)]} 0 0 [junit4] 2> 1643206 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1617528944807903233)]} 0 0 [junit4] 2> 1643206 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1617528944808951808)]} 0 0 [junit4] 2> 1643207 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1617528944808951808)]} 0 0 [junit4] 2> 1643207 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1617528944810000384)]} 0 0 [junit4] 2> 1643208 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1617528944811048960)]} 0 0 [junit4] 2> 1643208 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1617528944811048960)]} 0 0 [junit4] 2> 1643209 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1617528944811048961)]} 0 0 [junit4] 2> 1643209 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1617528944812097536)]} 0 0 [junit4] 2> 1643209 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1617528944812097536)]} 0 0 [junit4] 2> 1643210 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1617528944813146112)]} 0 0 [junit4] 2> 1643210 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1617528944813146112)]} 0 0 [junit4] 2> 1643211 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1617528944814194688,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1643211 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@31225f2b commitCommandVersion:1617528944814194688 [junit4] 2> 1643217 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@26d6d8ec[collection1] main] [junit4] 2> 1643217 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1643218 INFO (searcherExecutor-6466-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@26d6d8ec[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.7.0):c9) Uninverting(_1(7.7.0):c59)))} [junit4] 2> 1643218 INFO (qtp1206878471-16570) [ 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 7 [junit4] 2> 1643219 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1617528944822583296,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1643219 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7653f107 commitCommandVersion:1617528944822583296 [junit4] 2> 1643223 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@390c1497[collection1] main] [junit4] 2> 1643223 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1643225 INFO (searcherExecutor-6480-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@390c1497[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.7.0):c9) Uninverting(_1(7.7.0):c59)))} [junit4] 2> 1643225 INFO (qtp1242102013-16589) [ 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> 1643228 ERROR (qtp1206878471-16572) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value" [junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236) [junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:270) [junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:187) [junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:272) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2541) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:709) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:515) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:377) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:323) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:724) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:531) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value" [junit4] 2> at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) [junit4] 2> at java.lang.Integer.parseInt(Integer.java:580) [junit4] 2> at java.lang.Integer.valueOf(Integer.java:766) [junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233) [junit4] 2> ... 39 more [junit4] 2> [junit4] 2> 1643228 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=non_numeric_value&wt=javabin&version=2} status=400 QTime=2 [junit4] 2> 1643228 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 1643228 INFO (qtp1206878471-16570) [ 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> 1643229 INFO (qtp1206878471-16572) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 1643229 INFO (qtp1206878471-16572) [ 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> 1643230 ERROR (qtp1206878471-16570) [ x:collection1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: For input string: "non_numeric_value" [junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:236) [junit4] 2> at org.apache.solr.search.QParser.getSortSpec(QParser.java:273) [junit4] 2> at org.apache.solr.handler.component.QueryComponent.prepare(QueryComponent.java:187) [junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:272) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2541) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:709) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:515) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:377) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:323) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:724) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:531) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:333) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:310) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:168) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:126) [junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:366) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> Caused by: java.lang.NumberFormatException: For input string: "non_numeric_value" [junit4] 2> at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) [junit4] 2> at java.lang.Integer.parseInt(Integer.java:580) [junit4] 2> at java.lang.Integer.valueOf(Integer.java:766) [junit4] 2> at org.apache.solr.common.params.SolrParams.getInt(SolrParams.java:233) [junit4] 2> ... 39 more [junit4] 2> [junit4] 2> 1643230 INFO (qtp1206878471-16570) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&rows=non_numeric_value&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 1643230 INFO (qtp1206878471-16572) [ 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> 1643233 INFO (qtp1242102013-16589) [ 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:46873//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1542595810733&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 1643234 INFO (qtp1242102013-16592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1542595810733&ids=11,122,3,103,126,105,117,108,140,130&isShard=true&shard.url=http://127.0.0.1:46873//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1643235 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:46873//collection1|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3 [junit4] 2> 1643236 INFO (qtp1206878471-16570) [ 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> 1643246 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:46873//collection1&rows=10&version=2&q=*:*&NOW=1542595810743&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 1643247 INFO (qtp1242102013-16592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1542595810743&ids=143,13,14,135,16,138,127,7,139,141&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:46873//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1643248 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:46873//collection1&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6 [junit4] 2> 1643249 INFO (qtp1206878471-16572) [ 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> 1643257 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:46873//collection1&rows=10&version=2&q=*:*&NOW=1542595810752&isShard=true&wt=javabin} hits=68 status=0 QTime=4 [junit4] 2> 1643258 INFO (qtp1242102013-16592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1542595810752&ids=12,101,124,114,137,16,107,140,10,142&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:46873//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1643259 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:46873//collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=9 [junit4] 2> 1643260 INFO (qtp1206878471-16570) [ 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> 1643262 INFO (qtp1242102013-16591) [ 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:46873//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1542595810763&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 1643263 INFO (qtp1242102013-16592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1542595810763&ids=122,145,102,113,125,103,126,148,138,139&isShard=true&shard.url=http://127.0.0.1:46873//collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1643264 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:46873//collection1|[ff01::114]:33332/&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 1643265 INFO (qtp1206878471-16572) [ 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> 1643266 INFO (qtp1242102013-16589) [ 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=[ff01::083]:33332/|http://127.0.0.1:46873//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1542595810767&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 1643267 INFO (qtp1242102013-16592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1542595810767&ids=2,123,14,3,4,104,127,17,129,120&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:46873//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1643268 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:46873//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 1643269 INFO (qtp1206878471-16570) [ 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> 1643270 INFO (qtp1242102013-16591) [ 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:46873//collection1&rows=10&version=2&q=*:*&NOW=1542595810771&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 1643271 INFO (qtp1242102013-16592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1542595810771&ids=100,12,114,125,126,149,117,1001,8,9&isShard=true&shard.url=http://127.0.0.1:46873//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1643271 INFO (qtp1242102013-16589) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:46873//collection1&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 1643272 INFO (qtp1206878471-16572) [ 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> 1643274 INFO (qtp1242102013-16589) [ 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=http://127.0.0.1:46873//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1542595810775&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 1643275 INFO (qtp1242102013-16592) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1542595810775&ids=143,111,12,112,136,5,17,106,7,120&isShard=true&shard.url=http://127.0.0.1:46873//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1643275 INFO (qtp1242102013-16591) [ x:collection1] o.a.s.c.S.Request [collection1] [...truncated too long message...] ng empty /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/classes/test/org/apache/solr/cloud/autoscaling/sim/package-info.class [copy] Copying 1 file to /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/classes/test common-solr.compile-test: compile-test: BUILD SUCCESSFUL Total time: 56 seconds [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDistributedSearch" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=191E2BA4A4E24DE8 -Dtests.slow=true -Dtests.locale=et-EE -Dtests.timezone=Asia/Kuwait -Dtests.asserts=true -Dtests.file.encoding=UTF-8 Buildfile: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/build.xml -clover.disable: ivy-configure: [ivy:configure] :: Apache Ivy 2.4.0 - 20141213170938 :: http://ant.apache.org/ivy/ :: [ivy:configure] :: loading settings :: file = /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/top-level-ivy-settings.xml install-junit4-taskdef: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: resolve-groovy: [ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all-caller;working [ivy:cachepath] confs: [default] [ivy:cachepath] found org.codehaus.groovy#groovy-all;2.4.15 in public [ivy:cachepath] :: resolution report :: resolve 19ms :: artifacts dl 0ms --------------------------------------------------------------------- | | modules || artifacts | | conf | number| search|dwnlded|evicted|| number|dwnlded| --------------------------------------------------------------------- | default | 1 | 0 | 0 | 0 || 1 | 0 | --------------------------------------------------------------------- -init-totals: -test: [mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test [junit4:pickseed] Seed property 'tests.seed' already defined: 191E2BA4A4E24DE8 [mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/temp [junit4] <JUnit4> says 今日は! Master seed: 191E2BA4A4E24DE8 [junit4] Executing 5 suites with 2 JVMs. [junit4] [junit4] Started J0 PID(10727@serv1-vm3). [junit4] Started J1 PID(10726@serv1-vm3). [junit4] HEARTBEAT J1 PID(10726@serv1-vm3): 2018-11-19T03:51:26, stalled for 68.9s at: TestDistributedSearch.test [junit4] HEARTBEAT J0 PID(10727@serv1-vm3): 2018-11-19T03:51:26, stalled for 69.0s at: TestDistributedSearch.test [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] OK 79.1s J0 | TestDistributedSearch.test [junit4] Completed [1/5] on J0 in 81.36s, 1 test [junit4] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] OK 82.2s J1 | TestDistributedSearch.test [junit4] Completed [2/5] on J1 in 84.45s, 1 test [junit4] [junit4] Duplicate suite name used with XML reports: org.apache.solr.TestDistributedSearch. This may confuse tools that process XML reports. Set 'ignoreDuplicateSuites' to true to skip this message. [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] OK 52.3s J0 | TestDistributedSearch.test [junit4] Completed [3/5] on J0 in 52.55s, 1 test [junit4] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] OK 53.4s J1 | TestDistributedSearch.test [junit4] Completed [4/5] on J1 in 53.72s, 1 test [junit4] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] OK 35.1s J0 | TestDistributedSearch.test [junit4] Completed [5/5] on J0 in 35.25s, 1 test [junit4] [junit4] JVM J0: 0.72 .. 172.93 = 172.21s [junit4] JVM J1: 0.72 .. 141.72 = 140.99s [junit4] Execution time total: 2 minutes 52 seconds [junit4] Tests summary: 5 suites, 5 tests [junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp]) [junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp]) [echo] 5 slowest tests: [junit4:tophints] 345.30s | org.apache.solr.cloud.api.collections.ShardSplitTest [junit4:tophints] 163.12s | org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest [junit4:tophints] 131.63s | org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest [junit4:tophints] 130.58s | org.apache.solr.cloud.cdcr.CdcrOpsAndBoundariesTest [junit4:tophints] 117.62s | org.apache.solr.update.SoftAutoCommitTest -check-totals: test-nocompile: BUILD SUCCESSFUL Total time: 2 minutes 55 seconds [repro] Failures: [repro] 0/5 failed: org.apache.solr.TestDistributedSearch [repro] Exiting with code 0 + 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=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2845) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2724) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2705) at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3084) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to Solaris VBOX at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741) at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357) at hudson.remoting.Channel.call(Channel.java:955) at hudson.FilePath.act(FilePath.java:1070) at hudson.FilePath.act(FilePath.java:1059) at hudson.FilePath.validateAntFileMask(FilePath.java:2703) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1835) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) Caused: hudson.FilePath$TunneledInterruptedException at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086) at hudson.remoting.UserRequest.perform(UserRequest.java:212) at hudson.remoting.UserRequest.perform(UserRequest.java:54) at hudson.remoting.Request$2.run(Request.java:369) at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath.act(FilePath.java:1072) at hudson.FilePath.act(FilePath.java:1059) at hudson.FilePath.validateAntFileMask(FilePath.java:2703) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1835) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error? [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac) Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 <Git Blamer> Using GitBlamer to create author and commit information for all warnings. <Git Blamer> GIT_COMMIT=b306b4cc53c2436f8f6fd29d9f2e0de705b1876e, workspace=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris [WARNINGS] Computing warning deltas based on reference build #914 Recording test results Setting ANT_1_8_2_HOME=/export/home/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=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
