Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/4449/
3 tests failed. FAILED: org.apache.solr.search.json.TestJsonRequest.testDistribJsonRequest Error Message: Error from server at http://127.0.0.1:39113/solr/collection1: Server Error request: http://127.0.0.1:39113/solr/collection1/select?shards=127.0.0.1:37611/solr/collection1,127.0.0.1:39113/solr/collection1,127.0.0.1:41594/solr/collection1&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&wt=json&indent=true&version=2.2 Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39113/solr/collection1: Server Error request: http://127.0.0.1:39113/solr/collection1/select?shards=127.0.0.1:37611/solr/collection1,127.0.0.1:39113/solr/collection1,127.0.0.1:41594/solr/collection1&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&wt=json&indent=true&version=2.2 at __randomizedtesting.SeedInfo.seed([B01A37D504B0D36F:A44041D851DD55A7]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:665) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1290) at org.apache.solr.SolrTestCaseHS.getQueryResponse(SolrTestCaseHS.java:206) at org.apache.solr.SolrTestCaseHS.getJSON(SolrTestCaseHS.java:227) at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:144) at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:257) at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:299) at org.apache.solr.search.json.TestJsonRequest.doJsonRequest(TestJsonRequest.java:101) at org.apache.solr.search.json.TestJsonRequest.testDistribJsonRequest(TestJsonRequest.java:80) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:566) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) 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:370) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) at java.base/java.lang.Thread.run(Thread.java:834) FAILED: org.apache.solr.search.json.TestJsonRequest.testLocalJsonRequest Error Message: Test abandoned because suite timeout was reached. Stack Trace: java.lang.Exception: Test abandoned because suite timeout was reached. at __randomizedtesting.SeedInfo.seed([B01A37D504B0D36F]:0) FAILED: junit.framework.TestSuite.org.apache.solr.search.json.TestJsonRequest Error Message: Suite timeout exceeded (>= 7200000 msec). Stack Trace: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec). at __randomizedtesting.SeedInfo.seed([B01A37D504B0D36F]:0) Build Log: [...truncated 15225 lines...] [junit4] Suite: org.apache.solr.search.json.TestJsonRequest [junit4] 2> 196979 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/server/solr/configsets/_default/conf' [junit4] 2> 196980 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/data-dir-6-001 [junit4] 2> 196980 WARN (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=16 numCloses=16 [junit4] 2> 196980 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 196981 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None") [junit4] 2> 196982 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 196982 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 196988 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 197010 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.s.IndexSchema Schema name=example [junit4] 2> 197080 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id [junit4] 2> 198714 WARN (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr]. Features requiring this directory may fail. [junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/filestore" "write") [junit4] 2> at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) [junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/filestore" "write") [junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?] [junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:897) ~[?:?] [junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:322) ~[?:?] [junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:752) ~[?:?] [junit4] 2> at java.io.File.mkdir(File.java:1323) ~[?:?] [junit4] 2> at java.io.File.mkdirs(File.java:1355) ~[?:?] [junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:519) ~[java/:?] [junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?] [junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?] [junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:614) ~[java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:170) ~[java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:142) ~[java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:148) ~[java/:?] [junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:111) ~[java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:806) ~[java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:796) ~[java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:657) ~[java/:?] [junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:646) ~[java/:?] [junit4] 2> at org.apache.solr.search.json.TestJsonRequest.beforeTests(TestJsonRequest.java:45) ~[test/:?] [junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?] [junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?] [junit4] 2> at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:882) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?] [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?] [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?] [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?] [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?] [junit4] 2> at java.lang.Thread.run(Thread.java:834) [?:?] [junit4] 2> 198715 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 198719 WARN (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@41bebc7[provider=null,keyStore=null,trustStore=null] [junit4] 2> 198719 WARN (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@41bebc7[provider=null,keyStore=null,trustStore=null] [junit4] 2> 198723 WARN (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@323ded9e[provider=null,keyStore=null,trustStore=null] [junit4] 2> 198723 WARN (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@323ded9e[provider=null,keyStore=null,trustStore=null] [junit4] 2> 198724 WARN (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info [junit4] 2> 198742 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores [junit4] 2> 198743 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 198766 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 198766 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 198766 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 198774 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 198793 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example [junit4] 2> 198863 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id [junit4] 2> 198877 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 198877 INFO (coreLoadExecutor-82-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@17a94d5e [junit4] 2> 198878 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/data-dir-6-001/] [junit4] 2> 198880 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 198888 WARN (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}} [junit4] 2> 198949 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 198949 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 198951 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 198951 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 198952 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=98.609375, floorSegmentMB=1.8818359375, forceMergeDeletesPctAllowed=6.858986161770878, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3223763893893981, deletesPctAllowed=42.516392798001085 [junit4] 2> 198952 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@39c0596a[collection1] main] [junit4] 2> 198953 WARN (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead. [junit4] 2> 198954 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 198954 INFO (coreLoadExecutor-82-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661223109659721728 [junit4] 2> 198956 INFO (SUITE-TestJsonRequest-seed#[B01A37D504B0D36F]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 198956 INFO (searcherExecutor-83-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@39c0596a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 198962 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDistribJsonRequest [junit4] 2> 199008 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 199008 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 199010 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS [junit4] 2> 199012 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 199012 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 199012 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 199015 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@37d49e{/solr,null,AVAILABLE} [junit4] 2> 199017 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1fb89fcb{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:37611} [junit4] 2> 199017 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.Server Started @199149ms [junit4] 2> 199017 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, hostContext=/solr, solrconfig=solrconfig-tlog.xml, hostPort=37611} [junit4] 2> 199034 ERROR (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 199034 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 199034 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 199034 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 199034 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr [junit4] 2> 199034 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-15T09:50:13.583385Z [junit4] 2> 199036 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s0-001/solr.xml [junit4] 2> 199044 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 200476 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 200478 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5ce95425[provider=null,keyStore=null,trustStore=null] [junit4] 2> 200478 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5ce95425[provider=null,keyStore=null,trustStore=null] [junit4] 2> 200483 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@70399446[provider=null,keyStore=null,trustStore=null] [junit4] 2> 200483 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@70399446[provider=null,keyStore=null,trustStore=null] [junit4] 2> 200487 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info [junit4] 2> 200507 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 200507 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 200538 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 200538 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 200538 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 200543 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s0-001/. [junit4] 2> 200544 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 200551 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 200569 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example [junit4] 2> 200646 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id [junit4] 2> 200662 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s0-001/./collection1, trusted=true [junit4] 2> 200663 INFO (coreLoadExecutor-96-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@17a94d5e [junit4] 2> 200663 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s0-001/./collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s0-001/./collection1/data/] [junit4] 2> 200666 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 200669 WARN (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}} [junit4] 2> 200736 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 200736 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 200738 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 200738 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 200739 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=98.609375, floorSegmentMB=1.8818359375, forceMergeDeletesPctAllowed=6.858986161770878, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3223763893893981, deletesPctAllowed=42.516392798001085 [junit4] 2> 200740 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7c5a97e2[collection1] main] [junit4] 2> 200741 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s0-001/collection1/conf [junit4] 2> 200742 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 200742 INFO (coreLoadExecutor-96-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661223111534575616 [junit4] 2> 200744 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.SolrTestCaseHS ===> Started solr server port=37611 home=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s0-001 [junit4] 2> 200746 INFO (searcherExecutor-97-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7c5a97e2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 200756 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 200756 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 200756 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS [junit4] 2> 200757 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 200757 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 200757 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 200757 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6664fe6d{/solr,null,AVAILABLE} [junit4] 2> 200759 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@17a249f5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:39113} [junit4] 2> 200759 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.Server Started @200890ms [junit4] 2> 200759 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, hostContext=/solr, solrconfig=solrconfig-tlog.xml, hostPort=39113} [junit4] 2> 200759 ERROR (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 200759 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 200759 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 200759 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 200759 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr [junit4] 2> 200760 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-15T09:50:15.308996Z [junit4] 2> 200760 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s1-001/solr.xml [junit4] 2> 200765 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 201046 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 201048 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@457c9fdf[provider=null,keyStore=null,trustStore=null] [junit4] 2> 201048 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@457c9fdf[provider=null,keyStore=null,trustStore=null] [junit4] 2> 201052 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7750bc5e[provider=null,keyStore=null,trustStore=null] [junit4] 2> 201053 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7750bc5e[provider=null,keyStore=null,trustStore=null] [junit4] 2> 201054 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info [junit4] 2> 201075 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 201075 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 201106 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 201123 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 201123 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 201127 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s1-001/. [junit4] 2> 201127 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 201134 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 201153 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example [junit4] 2> 201222 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id [junit4] 2> 201237 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s1-001/./collection1, trusted=true [junit4] 2> 201238 INFO (coreLoadExecutor-110-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@17a94d5e [junit4] 2> 201238 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s1-001/./collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s1-001/./collection1/data/] [junit4] 2> 201240 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 201244 WARN (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}} [junit4] 2> 201314 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 201314 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 201316 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 201316 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 201317 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=98.609375, floorSegmentMB=1.8818359375, forceMergeDeletesPctAllowed=6.858986161770878, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3223763893893981, deletesPctAllowed=42.516392798001085 [junit4] 2> 201318 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@50a51325[collection1] main] [junit4] 2> 201319 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s1-001/collection1/conf [junit4] 2> 201320 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 201320 INFO (coreLoadExecutor-110-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661223112140652544 [junit4] 2> 201321 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.SolrTestCaseHS ===> Started solr server port=39113 home=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s1-001 [junit4] 2> 201323 INFO (searcherExecutor-111-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@50a51325[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 201334 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 201334 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 201334 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS [junit4] 2> 201335 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 201335 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 201335 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 201335 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@66f74648{/solr,null,AVAILABLE} [junit4] 2> 201337 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7d3917de{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:41594} [junit4] 2> 201337 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.s.Server Started @201469ms [junit4] 2> 201337 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, hostContext=/solr, solrconfig=solrconfig-tlog.xml, hostPort=41594} [junit4] 2> 201338 ERROR (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 201338 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 201338 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 201338 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 201338 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr [junit4] 2> 201338 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-15T09:50:15.887359Z [junit4] 2> 201338 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s2-001/solr.xml [junit4] 2> 201343 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 201936 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 201938 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6f453c49[provider=null,keyStore=null,trustStore=null] [junit4] 2> 201938 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6f453c49[provider=null,keyStore=null,trustStore=null] [junit4] 2> 201942 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2c3786f7[provider=null,keyStore=null,trustStore=null] [junit4] 2> 201943 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2c3786f7[provider=null,keyStore=null,trustStore=null] [junit4] 2> 201944 WARN (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info [junit4] 2> 201965 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 201965 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 201995 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 202012 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 202012 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a94d5e [junit4] 2> 202016 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s2-001/. [junit4] 2> 202016 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 202023 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 202042 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=example [junit4] 2> 202113 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id [junit4] 2> 202129 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s2-001/./collection1, trusted=true [junit4] 2> 202129 INFO (coreLoadExecutor-124-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@17a94d5e [junit4] 2> 202129 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s2-001/./collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s2-001/./collection1/data/] [junit4] 2> 202132 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 202135 WARN (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}} [junit4] 2> 202204 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 202204 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 202206 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 202206 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 202208 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=98.609375, floorSegmentMB=1.8818359375, forceMergeDeletesPctAllowed=6.858986161770878, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3223763893893981, deletesPctAllowed=42.516392798001085 [junit4] 2> 202209 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5124eef5[collection1] main] [junit4] 2> 202211 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s2-001/collection1/conf [junit4] 2> 202212 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 202212 INFO (coreLoadExecutor-124-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661223113075982336 [junit4] 2> 202213 INFO (TEST-TestJsonRequest.testDistribJsonRequest-seed#[B01A37D504B0D36F]) [ ] o.a.s.SolrTestCaseHS ===> Started solr server port=41594 home=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.search.json.TestJsonRequest_B01A37D504B0D36F-001/s2-001 [junit4] 2> 202215 INFO (searcherExecutor-125-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5124eef5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 202396 INFO (qtp598175077-441) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1661223113265774592)} 0 15 [junit4] 2> 202418 INFO (qtp295640809-461) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1661223113289891840)} 0 2 [junit4] 2> 202423 INFO (qtp741173747-481) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1661223113295134720)} 0 2 [junit4] 2> 202428 INFO (qtp295640809-462) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1661223113300377600)]} 0 2 [junit4] 2> 202430 INFO (qtp295640809-460) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2 (1661223113304571904)]} 0 0 [junit4] 2> 202434 INFO (qtp741173747-482) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3 (1661223113306669056)]} 0 1 [junit4] 2> 202438 INFO (qtp598175077-442) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661223113312960512,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 202438 INFO (qtp598175077-442) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 202439 INFO (qtp598175077-442) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 202439 INFO (qtp598175077-442) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1 [junit4] 2> 202441 INFO (qtp295640809-461) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661223113316106240,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 202441 INFO (qtp295640809-461) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5ed62c2e commitCommandVersion:1661223113316106240 [junit4] 2> 202455 INFO (qtp295640809-461) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@67bcc2db[collection1] main] [junit4] 2> 202456 INFO (qtp295640809-461) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 202460 INFO (searcherExecutor-111-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@67bcc2db[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c2:[diagnostics={lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, java.version=11.0.4, timestamp=1584265816998, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-170-generic, java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 202460 INFO (qtp295640809-461) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 20 [junit4] 2> 202463 INFO (qtp741173747-480) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661223113339174912,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 202463 INFO (qtp741173747-480) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@130f511b commitCommandVersion:1661223113339174912 [junit4] 2> 202469 INFO (qtp741173747-480) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4beb073d[collection1] main] [junit4] 2> 202469 INFO (qtp741173747-480) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 202473 INFO (searcherExecutor-125-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4beb073d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c1:[diagnostics={lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, java.version=11.0.4, timestamp=1584265817014, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-170-generic, java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 202474 INFO (qtp741173747-480) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 11 [junit4] 2> 202478 INFO (qtp741173747-481) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4 (1661223113352806400)]} 0 2 [junit4] 2> 202482 INFO (qtp598175077-440) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5 (1661223113357000704)]} 0 2 [junit4] 2> 202484 INFO (qtp598175077-441) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661223113361195008,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 202484 INFO (qtp598175077-441) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@43a0a44f commitCommandVersion:1661223113361195008 [junit4] 2> 202490 INFO (qtp598175077-441) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7ba65edb[collection1] main] [junit4] 2> 202492 INFO (qtp598175077-441) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 202496 INFO (searcherExecutor-97-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7ba65edb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c1:[diagnostics={lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, java.version=11.0.4, timestamp=1584265817036, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-170-generic, java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 202497 INFO (qtp598175077-441) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 13 [junit4] 2> 202499 INFO (qtp295640809-462) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661223113376923648,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 202501 INFO (qtp295640809-462) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 202501 INFO (qtp295640809-462) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 202501 INFO (qtp295640809-462) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2 [junit4] 2> 202503 INFO (qtp741173747-482) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661223113381117952,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 202503 INFO (qtp741173747-482) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@130f511b commitCommandVersion:1661223113381117952 [junit4] 2> 202509 INFO (qtp741173747-482) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@441ab8a0[collection1] main] [junit4] 2> 202511 INFO (qtp741173747-482) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 202514 INFO (searcherExecutor-125-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@441ab8a0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c1:[diagnostics={lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, java.version=11.0.4, timestamp=1584265817014, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-170-generic, java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(9.0.0):c1:[diagnostics={lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, java.version=11.0.4, timestamp=1584265817055, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-170-generic, java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 202515 INFO (qtp741173747-482) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 12 [junit4] 2> 202519 INFO (qtp598175077-442) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6 (1661223113395798016)]} 0 2 [junit4] 2> 202521 INFO (qtp598175077-440) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661223113399992320,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 202521 INFO (qtp598175077-440) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@43a0a44f commitCommandVersion:1661223113399992320 [junit4] 2> 202527 INFO (qtp598175077-440) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ab8d16b[collection1] main] [junit4] 2> 202528 INFO (qtp598175077-440) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 202531 INFO (searcherExecutor-97-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ab8d16b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c1:[diagnostics={lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, java.version=11.0.4, timestamp=1584265817036, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-170-generic, java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(9.0.0):c1:[diagnostics={lucene.version=9.0.0, java.vm.version=11.0.4+10-LTS, java.version=11.0.4, timestamp=1584265817073, java.vendor=Oracle Corporation, os=Linux, os.version=4.4.0-170-generic, java.runtime.version=11.0.4+10-LTS, os.arch=amd64, source=flush}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} [junit4] 2> 202532 INFO (qtp598175077-440) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 11 [junit4] 2> 202534 INFO (qtp295640809-460) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661223113413623808,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 202534 INFO (qtp295640809-460) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 202534 INFO (qtp295640809-460) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 202534 INFO (qtp295640809-460) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 0 [junit4] 2> 202536 INFO (qtp741173747-480) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661223113415720960,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 202536 INFO (qtp741173747-480) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 202536 INFO (qtp741173747-480) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 202536 INFO (qtp741173747-480) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 0 [junit4] 2> 202694 INFO (qtp741173747-480) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={df=text&distrib=false&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=127.0.0.1:41594/solr/collection1&rows=10&version=2&q=cat_s:A&omitHeader=false&NOW=1584265817088&json={query:'cat_s:A'}&isShard=true&wt=javabin} hits=1 status=0 QTime=3 [junit4] 2> 202694 INFO (qtp295640809-460) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={df=text&distrib=false&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=127.0.0.1:39113/solr/collection1&rows=10&version=2&q=cat_s:A&omitHeader=false&NOW=1584265817088&json={query:'cat_s:A'}&isShard=true&wt=javabin} hits=1 status=0 QTime=3 [junit4] 2> 202694 INFO (qtp598175077-442) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={df=text&distrib=false&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=127.0.0.1:37611/solr/collection1&rows=10&version=2&q=cat_s:A&omitHeader=false&NOW=1584265817088&json={query:'cat_s:A'}&isShard=true&wt=javabin} hits=0 status=0 QTime=3 [junit4] 2> 202713 INFO (qtp741173747-481) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=cat_s:A&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1584265817088&json={query:'cat_s:A'}&ids=4&isShard=true&shard.url=127.0.0.1:41594/solr/collection1&wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 202713 INFO (qtp295640809-461) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=cat_s:A&df=text&distrib=false&omitHeader=false&shards.purpose=64&NOW=1584265817088&json={query:'cat_s:A'}&ids=1&isShard=true&shard.url=127.0.0.1:39113/solr/collection1&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 202717 INFO (qtp598175077-441) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={shards=127.0.0.1:37611/solr/collection1,127.0.0.1:39113/solr/collection1,127.0.0.1:41594/solr/collection1&indent=true&json={query:'cat_s:A'}&wt=json&version=2.2} hits=2 status=0 QTime=177 [junit4] 2> 202725 ERROR (qtp741173747-482) [ x:collection1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Expected JSON Object but got Long=5 [junit4] 2> at org.apache.solr.request.json.ObjectUtil.mergeObjects(ObjectUtil.java:115) [junit4] 2> at org.apache.solr.request.json.RequestUtil.mergeJSON(RequestUtil.java:285) [junit4] 2> at org.apache.solr.request.json.RequestUtil.processParams(RequestUtil.java:180) [junit4] 2> at org.apache.solr.util.SolrPluginUtils.setDefaults(SolrPluginUtils.java:165) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:206) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2564) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:802) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:579) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:767) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:500) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:834) [junit4] 2> [junit4] 2> 202725 INFO (qtp741173747-482) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={shards=127.0.0.1:37611/solr/collection1,127.0.0.1:39113/solr/collection1,127.0.0.1:41594/solr/collection1&q=*:*&indent=true&json=5&wt=json&version=2.2} status=400 QTime=1 [junit4] 2> 202736 INFO (qtp295640809-461) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={df=text&distrib=false&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=127.0.0.1:39113/solr/collection1&rows=1&json.limit=1&version=2&q=cat_s:A&omitHeader=false&NOW=1584265817277&isShard=true&wt=javabin} hits=1 status=0 QTime=1 [junit4] 2> 202739 INFO (qtp598175077-441) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={df=text&distrib=false&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=127.0.0.1:37611/solr/collection1&rows=1&json.limit=1&version=2&q=cat_s:A&omitHeader=false&NOW=1584265817277&isShard=true&wt=javabin} hits=0 status=0 QTime=1 [junit4] 2> 202741 INFO (qtp741173747-481) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={df=text&distrib=false&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=127.0.0.1:41594/solr/collection1&rows=1&json.limit=1&version=2&q=cat_s:A&omitHeader=false&NOW=1584265817277&isShard=true&wt=javabin} hits=1 status=0 QTime=2 [junit4] 2> 202745 INFO (qtp295640809-462) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=127.0.0.1:39113/solr/collection1&rows=1&json.limit=1&version=2&q=cat_s:A&omitHeader=false&NOW=1584265817277&ids=1&isShard=true&wt=javabin} status=0 QTime=0 [junit4] 2> 202747 INFO (qtp741173747-480) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={shards=127.0.0.1:37611/solr/collection1,127.0.0.1:39113/solr/collection1,127.0.0.1:41594/solr/collection1&q=cat_s:A&indent=true&json.limit=1&wt=json&version=2.2} hits=2 status=0 QTime=18 [junit4] 2> 217753 ERROR (qtp295640809-460) [ x:collection1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:41594/solr/collection1/select [junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:443) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:209) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2564) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:802) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:579) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:767) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:500) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:834) [junit4] 2> Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:41594/solr/collection1/select [junit4] 2> at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:407) [junit4] 2> at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:753) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1290) [junit4] 2> at org.apache.solr.handler.component.HttpShardHandler.request(HttpShardHandler.java:208) [junit4] 2> at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:170) [junit4] 2> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) [junit4] 2> at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [junit4] 2> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:180) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [junit4] 2> ... 1 more [junit4] 2> Caused by: java.util.concurrent.TimeoutException [junit4] 2> at org.eclipse.jetty.client.util.InputStreamResponseListener.get(InputStreamResponseListener.java:216) [junit4] 2> at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:398) [junit4] 2> ... 12 more [junit4] 2> [junit4] 2> 217753 INFO (qtp295640809-460) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={shards=127.0.0.1:37611/solr/collection1,127.0.0.1:39113/solr/collection1,127.0.0.1:41594/solr/collection1&indent=true&json={query:'cat_s:A'}&json={filter:'where_s:NY'}&wt=json&version=2.2} status=500 QTime=15003 [junit4] 2> 217753 ERROR (qtp295640809-460) [ x:collection1 ] o.a.s.s.HttpSolrCall null:org.apache.solr.common.SolrException: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:41594/solr/collection1/select [junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:443) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:209) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2564) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:802) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:579) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:767) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:500) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:834) [junit4] 2> Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:41594/solr/collection1/select [junit4] 2> at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:407) [junit4] 2> at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:753) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1290) [junit4] 2> at org.apache.solr.handler.component.HttpShardHandler.request(HttpShardHandler.java:208) [junit4] 2> at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:170) [junit4] 2> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) [junit4] 2> at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [junit4] 2> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:180) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [junit4] 2> at java.base/java.util.concur [...truncated too long message...] [email protected]/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234) [junit4] 2> at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123) [junit4] 2> at app//org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:875) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:925) [junit4] 2> at [email protected]/java.lang.Thread.run(Thread.java:834) [junit4] 2> 84) Thread[id=438, name=qtp598175077-438, state=RUNNABLE, group=TGRP-TestJsonRequest] [junit4] 2> at [email protected]/sun.nio.ch.EPoll.wait(Native Method) [junit4] 2> at [email protected]/sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:120) [junit4] 2> at [email protected]/sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:124) [junit4] 2> at [email protected]/sun.nio.ch.SelectorImpl.select(SelectorImpl.java:141) [junit4] 2> at app//org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:472) [junit4] 2> at app//org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:409) [junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:360) [junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:184) [junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) [junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:135) [junit4] 2> at app//org.eclipse.jetty.io.ManagedSelector$$Lambda$202/0x0000000100459440.run(Unknown Source) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938) [junit4] 2> at [email protected]/java.lang.Thread.run(Thread.java:834) [junit4] 2> 85) Thread[id=475, name=qtp741173747-475, state=RUNNABLE, group=TGRP-TestJsonRequest] [junit4] 2> at [email protected]/sun.nio.ch.EPoll.wait(Native Method) [junit4] 2> at [email protected]/sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:120) [junit4] 2> at [email protected]/sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:124) [junit4] 2> at [email protected]/sun.nio.ch.SelectorImpl.select(SelectorImpl.java:141) [junit4] 2> at app//org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:472) [junit4] 2> at app//org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:409) [junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:360) [junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:184) [junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171) [junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:135) [junit4] 2> at app//org.eclipse.jetty.io.ManagedSelector$$Lambda$202/0x0000000100459440.run(Unknown Source) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806) [junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938) [junit4] 2> at [email protected]/java.lang.Thread.run(Thread.java:834) [junit4] 2> 86) Thread[id=433, name=searcherExecutor-83-thread-1, state=WAITING, group=TGRP-TestJsonRequest] [junit4] 2> at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] 2> at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194) [junit4] 2> at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2081) [junit4] 2> at [email protected]/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:433) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [junit4] 2> at [email protected]/java.lang.Thread.run(Thread.java:834) [junit4] 2> 87) Thread[id=499, name=Connector-Scheduler-17a249f5-1, state=WAITING, group=TGRP-TestJsonRequest] [junit4] 2> at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] 2> at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194) [junit4] 2> at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2081) [junit4] 2> at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1170) [junit4] 2> at [email protected]/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [junit4] 2> at [email protected]/java.lang.Thread.run(Thread.java:834) [junit4] 2> 88) Thread[id=505, name=h2sc-43-thread-2, state=WAITING, group=TGRP-TestJsonRequest] [junit4] 2> at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] 2> at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194) [junit4] 2> at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2081) [junit4] 2> at app//org.eclipse.jetty.util.BlockingArrayQueue.take(BlockingArrayQueue.java:342) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [junit4] 2> at [email protected]/java.lang.Thread.run(Thread.java:834) [junit4] 2> 89) Thread[id=528, name=h2sc-49-thread-5, state=WAITING, group=TGRP-TestJsonRequest] [junit4] 2> at [email protected]/jdk.internal.misc.Unsafe.park(Native Method) [junit4] 2> at [email protected]/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194) [junit4] 2> at [email protected]/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2081) [junit4] 2> at app//org.eclipse.jetty.util.BlockingArrayQueue.take(BlockingArrayQueue.java:342) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114) [junit4] 2> at [email protected]/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [junit4] 2> at [email protected]/java.lang.Thread.run(Thread.java:834) [junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {where_s_single_not_uninvert_dv=PostingsFormat(name=MockRandom), _root_=Lucene84, where_s=PostingsFormat(name=LuceneFixedGap), where_s_multi_not_uninvert=PostingsFormat(name=MockRandom), where_s_multi_not_uninvert_dv=FST50, cat_s=Lucene84, id=PostingsFormat(name=LuceneFixedGap), where_s_single_not_uninvert=Lucene84}, docValues:{where_s_single_not_uninvert_dv=DocValuesFormat(name=Lucene80), _version_=DocValuesFormat(name=Asserting), where_s_multi_not_uninvert_dv=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1267, maxMBSortInHeap=5.9820590492060495, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2da447e), locale=fr-CF, timezone=EAT [junit4] 2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=8,threads=101,free=236246576,total=528482304 [junit4] 2> NOTE: All tests run in this JVM: [SolrIndexMetricsTest, HdfsDirectoryTest, TestMinHashQParser, TestFieldCacheWithThreads, TestJsonRequest] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestJsonRequest -Dtests.seed=B01A37D504B0D36F -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=fr-CF -Dtests.timezone=EAT -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J2 | TestJsonRequest (suite) <<< [junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec). [junit4] > at __randomizedtesting.SeedInfo.seed([B01A37D504B0D36F]:0) [junit4] Completed [759/905 (1!)] on J2 in 7213.25s, 2 tests, 3 errors <<< FAILURES! [...truncated 53049 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
