You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/07/31 18:49:55 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk-12.0.2) - Build #
6351 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/6351/
Java: 64bit/jdk-12.0.2 -XX:+UseCompressedOops -XX:+UseParallelGC
4 tests failed.
FAILED: org.apache.lucene.store.TestRAFDirectory.testRandomShort
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([5B9917516F04C5E8]:0)
FAILED: junit.framework.TestSuite.org.apache.lucene.store.TestRAFDirectory
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([5B9917516F04C5E8]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.DistribCursorPagingTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.DistribCursorPagingTest: 1) Thread[id=12396, name=SessionTracker, state=TIMED_WAITING, group=TGRP-DistribCursorPagingTest] at java.base@12.0.2/java.lang.Thread.sleep(Native Method) at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:157)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.DistribCursorPagingTest:
1) Thread[id=12396, name=SessionTracker, state=TIMED_WAITING, group=TGRP-DistribCursorPagingTest]
at java.base@12.0.2/java.lang.Thread.sleep(Native Method)
at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:157)
at __randomizedtesting.SeedInfo.seed([78B931CDA9BD8371]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([2BC0C89C144274C7]:0)
Build Log:
[...truncated 8448 lines...]
[junit4] Suite: org.apache.lucene.store.TestRAFDirectory
[junit4] 2> Aug 01, 2020 5:45:05 AM com.carrotsearch.randomizedtesting.ThreadLeakControl$2 evaluate
[junit4] 2> WARNING: Suite execution timed out: org.apache.lucene.store.TestRAFDirectory
[junit4] 2> 1) Thread[id=14, name=TEST-TestRAFDirectory.testRandomShort-seed#[5B9917516F04C5E8], state=RUNNABLE, group=TGRP-TestRAFDirectory]
[junit4] 2> at app//org.apache.lucene.store.BufferedIndexInput.readShort(BufferedIndexInput.java:247)
[junit4] 2> at app//org.apache.lucene.store.BaseDirectoryTestCase.testRandomShort(BaseDirectoryTestCase.java:902)
[junit4] 2> at java.base@12.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at java.base@12.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at java.base@12.0.2/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.base@12.0.2/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
[junit4] 2> at app//org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at app//org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
[junit4] 2> at app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$$Lambda$98/0x00000008011e6440.run(Unknown Source)
[junit4] 2> at java.base@12.0.2/java.lang.Thread.run(Thread.java:835)
[junit4] 2> 2) Thread[id=13, name=SUITE-TestRAFDirectory-seed#[5B9917516F04C5E8], state=RUNNABLE, group=TGRP-TestRAFDirectory]
[junit4] 2> at java.base/java.lang.Thread.getStackTrace(Thread.java:1602)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$4.run(ThreadLeakControl.java:698)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$4.run(ThreadLeakControl.java:695)
[junit4] 2> at java.base/java.security.AccessController.doPrivileged(AccessController.java:310)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.getStackTrace(ThreadLeakControl.java:695)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.getThreadsWithTraces(ThreadLeakControl.java:711)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.formatThreadStacksFull(ThreadLeakControl.java:691)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.access$1000(ThreadLeakControl.java:67)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$2.evaluate(ThreadLeakControl.java:417)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:712)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.access$200(RandomizedRunner.java:138)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$2.run(RandomizedRunner.java:633)
[junit4] 2> 3) Thread[id=11, name=JUnit4-serializer-daemon, state=TIMED_WAITING, group=main]
[junit4] 2> at java.base@12.0.2/java.lang.Thread.sleep(Native Method)
[junit4] 2> at app//com.carrotsearch.ant.tasks.junit4.events.Serializer$1.run(Serializer.java:50)
[junit4] 2> 4) Thread[id=1, name=main, state=WAITING, group=main]
[junit4] 2> at java.base@12.0.2/java.lang.Object.wait(Native Method)
[junit4] 2> at java.base@12.0.2/java.lang.Thread.join(Thread.java:1308)
[junit4] 2> at java.base@12.0.2/java.lang.Thread.join(Thread.java:1375)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:643)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.run(RandomizedRunner.java:500)
[junit4] 2> at app//com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.execute(SlaveMain.java:269)
[junit4] 2> at app//com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.main(SlaveMain.java:394)
[junit4] 2> at app//com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe.main(SlaveMainSafe.java:13)
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRAFDirectory -Dtests.method=testRandomShort -Dtests.seed=5B9917516F04C5E8 -Dtests.slow=true -Dtests.locale=en-SB -Dtests.timezone=Pacific/Wallis -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.38s J4 | TestRAFDirectory.testRandomShort <<<
[junit4] > Throwable #1: java.lang.Exception: Test abandoned because suite timeout was reached.
[junit4] > at __randomizedtesting.SeedInfo.seed([5B9917516F04C5E8]:0)
[junit4] 2> Aug 01, 2020 5:45:05 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=en-SB, timezone=Pacific/Wallis
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=1,free=216610072,total=244318208
[junit4] 2> NOTE: All tests run in this JVM: [TestRAFDirectory]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRAFDirectory -Dtests.seed=5B9917516F04C5E8 -Dtests.slow=true -Dtests.locale=en-SB -Dtests.timezone=Pacific/Wallis -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J4 | TestRAFDirectory (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([5B9917516F04C5E8]:0)
[junit4] Completed [5/17 (1!)] on J4 in 4.86s, 24 tests, 2 errors <<< FAILURES!
[...truncated 4959 lines...]
[junit4] Suite: org.apache.solr.cloud.DistribCursorPagingTest
[junit4] 2> 1206039 INFO (SUITE-DistribCursorPagingTest-seed#[78B931CDA9BD8371]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1206040 INFO (SUITE-DistribCursorPagingTest-seed#[78B931CDA9BD8371]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/data-dir-105-001
[junit4] 2> 1206040 WARN (SUITE-DistribCursorPagingTest-seed#[78B931CDA9BD8371]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 1206040 INFO (SUITE-DistribCursorPagingTest-seed#[78B931CDA9BD8371]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1206041 INFO (SUITE-DistribCursorPagingTest-seed#[78B931CDA9BD8371]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="https://issues.apache.org/jira/browse/SOLR-9182 - causes OOM") w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1206041 INFO (SUITE-DistribCursorPagingTest-seed#[78B931CDA9BD8371]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1206043 INFO (SUITE-DistribCursorPagingTest-seed#[78B931CDA9BD8371]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /g_oi/mj
[junit4] 2> 1206050 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1206050 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1206051 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1206053 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1206151 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer start zk server on port: 61325
[junit4] 2> 1206154 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:61325
[junit4] 2> 1206154 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61325
[junit4] 2> 1206154 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 61325
[junit4] 2> 1206165 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1206179 INFO (zkConnectionManagerCallback-8067-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1206179 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1206202 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1206214 INFO (zkConnectionManagerCallback-8069-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1206214 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1206217 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-deeppaging.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1206224 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema-sorts.xml to /configs/conf1/schema.xml
[junit4] 2> 1206230 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1206240 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1206253 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1206262 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1206271 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1206282 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1206296 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1206302 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1206309 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1206320 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 1206934 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1206934 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1206934 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1206942 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1206942 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1206942 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1206944 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3ef495f3{/g_oi/mj,null,AVAILABLE}
[junit4] 2> 1206946 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@691f643{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61348}
[junit4] 2> 1206946 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.e.j.s.Server Started @1206996ms
[junit4] 2> 1206946 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/g_oi/mj, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/tempDir-001/control/data, hostPort=61348, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/control-001/cores}
[junit4] 2> 1206946 ERROR (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1206946 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1206947 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1206947 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1206947 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 1206947 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-07-31T18:07:51.623248Z
[junit4] 2> 1206952 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1206956 INFO (zkConnectionManagerCallback-8071-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1206956 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1206963 WARN (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]-SendThread(127.0.0.1:61325)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10038ff1daf0002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0002, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0002, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 1207064 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1207064 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/control-001/solr.xml
[junit4] 2> 1207067 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1207187 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1207192 WARN (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@442e539[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1207192 WARN (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@442e539[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1207197 WARN (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3901b643[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1207197 WARN (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3901b643[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1207198 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61325/solr
[junit4] 2> 1207203 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1207210 INFO (zkConnectionManagerCallback-8081-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1207212 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1207215 WARN (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]-SendThread(127.0.0.1:61325)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10038ff1daf0003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 1207319 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1207323 INFO (zkConnectionManagerCallback-8083-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1207323 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1207771 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61348_g_oi%2Fmj
[junit4] 2> 1207773 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.Overseer Overseer (id=72120262403751940-127.0.0.1:61348_g_oi%2Fmj-n_0000000000) starting
[junit4] 2> 1207810 INFO (OverseerStateUpdate-72120262403751940-127.0.0.1:61348_g_oi%2Fmj-n_0000000000) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:61348_g_oi%2Fmj
[junit4] 2> 1207810 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61348_g_oi%2Fmj
[junit4] 2> 1207815 INFO (OverseerStateUpdate-72120262403751940-127.0.0.1:61348_g_oi%2Fmj-n_0000000000) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1207830 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1207831 WARN (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] 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> 1207861 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1207899 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1207910 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1207910 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1207912 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/control-001/cores
[junit4] 2> 1207927 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1207931 INFO (zkConnectionManagerCallback-8098-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1207931 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1207935 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1207938 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61325/solr ready
[junit4] 2> 1207943 INFO (qtp584471706-12423) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:61348_g_oi%252Fmj&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1207955 INFO (OverseerThreadFactory-8088-thread-1-processing-n:127.0.0.1:61348_g_oi%2Fmj) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 1208114 INFO (OverseerStateUpdate-72120262403751940-127.0.0.1:61348_g_oi%2Fmj-n_0000000000) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:61348/g_oi/mj",
[junit4] 2> "node_name":"127.0.0.1:61348_g_oi%2Fmj",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1208323 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1208323 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1208369 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1208380 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 1208382 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=sortable
[junit4] 2> 1208465 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema sortable/1.6 with uniqueid field id
[junit4] 2> 1208466 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1208466 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 1208468 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=45, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=80.59375, floorSegmentMB=0.76953125, forceMergeDeletesPctAllowed=9.675258855277676, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=32.71663055679884
[junit4] 2> 1208536 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1208536 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1208538 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1208538 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1208538 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=32, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5810722084986257]
[junit4] 2> 1208541 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1208542 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1208542 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1208542 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1673756801203437568
[junit4] 2> 1208542 INFO (searcherExecutor-8100-thread-1-processing-n:127.0.0.1:61348_g_oi%2Fmj x:control_collection_shard1_replica_n1 c:control_collection s:shard1 r:core_node2) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1208555 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1208556 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 1208568 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1208568 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1208568 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:61348/g_oi/mj/control_collection_shard1_replica_n1/
[junit4] 2> 1208570 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1208571 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:61348/g_oi/mj/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 1208571 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72120262403751940-core_node2-n_0000000000
[junit4] 2> 1208579 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:61348/g_oi/mj/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 1208684 INFO (zkCallback-8082-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1208684 INFO (zkCallback-8082-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1208688 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1208691 INFO (qtp584471706-12422) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=369
[junit4] 2> 1208695 INFO (qtp584471706-12423) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1208802 INFO (zkCallback-8082-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1208802 INFO (zkCallback-8082-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1208802 INFO (zkCallback-8082-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1208806 INFO (qtp584471706-12423) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:61348_g_oi%252Fmj&wt=javabin&version=2} status=0 QTime=864
[junit4] 2> 1208807 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 1208817 WARN (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]-SendThread(127.0.0.1:61325)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10038ff1daf0005.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 1208931 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1208934 INFO (zkConnectionManagerCallback-8109-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1208935 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1208938 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1208941 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61325/solr ready
[junit4] 2> 1208941 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1208942 INFO (qtp584471706-12423) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1208952 INFO (OverseerThreadFactory-8088-thread-2-processing-n:127.0.0.1:61348_g_oi%2Fmj) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 1208954 INFO (OverseerCollectionConfigSetProcessor-72120262403751940-127.0.0.1:61348_g_oi%2Fmj-n_0000000000) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1209168 WARN (OverseerThreadFactory-8088-thread-2-processing-n:127.0.0.1:61348_g_oi%2Fmj) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 1209173 INFO (qtp584471706-12423) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1209177 INFO (qtp584471706-12423) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&wt=javabin&version=2} status=0 QTime=235
[junit4] 2> 1209179 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1209179 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1209185 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1209185 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1209186 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1209186 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1209186 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 1209626 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-1-001 of type NRT for shard2
[junit4] 2> 1209627 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1209627 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1209627 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7304f12d{/g_oi/mj,null,AVAILABLE}
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@14883618{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61371}
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.Server Started @1209680ms
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/g_oi/mj, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/tempDir-001/jetty1, hostPort=61371, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-1-001/cores}
[junit4] 2> 1209630 ERROR (closeThreadPool-8110-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 1209630 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-07-31T18:07:54.306889Z
[junit4] 2> 1209635 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1209640 INFO (zkConnectionManagerCallback-8112-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1209640 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1209644 WARN (closeThreadPool-8110-thread-1-SendThread(127.0.0.1:61325)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10038ff1daf0007.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 1209745 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1209745 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-1-001/solr.xml
[junit4] 2> 1209748 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1209992 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1209994 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@730c9cc6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1209994 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@730c9cc6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1210020 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1302a1ce[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1210020 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1302a1ce[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1210021 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61325/solr
[junit4] 2> 1210027 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1210028 INFO (zkConnectionManagerCallback-8122-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1210028 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1210035 WARN (closeThreadPool-8110-thread-1-SendThread(127.0.0.1:61325)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10038ff1daf0008.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 1210143 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1210147 INFO (zkConnectionManagerCallback-8124-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1210147 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1210169 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1210190 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.c.ZkController Publish node=127.0.0.1:61371_g_oi%2Fmj as DOWN
[junit4] 2> 1210195 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1210195 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61371_g_oi%2Fmj
[junit4] 2> 1210199 INFO (zkCallback-8082-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1210200 INFO (zkCallback-8123-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1210200 INFO (zkCallback-8108-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1210211 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1210212 WARN (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] 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> 1210236 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1210266 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1210270 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1210270 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1210275 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61371_g_oi%2Fmj ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-1-001/cores
[junit4] 2> 1210283 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:61371_g_oi%2Fmj
[junit4] 2> 1210340 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-2-001 of type NRT for shard1
[junit4] 2> 1210340 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1210340 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1210341 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1210349 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1210349 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1210349 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1210350 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@24a9358e{/g_oi/mj,null,AVAILABLE}
[junit4] 2> 1210352 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@107a8f44{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61377}
[junit4] 2> 1210352 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.Server Started @1210402ms
[junit4] 2> 1210352 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/g_oi/mj, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/tempDir-001/jetty2, hostPort=61377, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-2-001/cores, replicaType=NRT}
[junit4] 2> 1210353 ERROR (closeThreadPool-8110-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1210353 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1210353 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1210353 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1210353 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 1210353 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-07-31T18:07:55.029275Z
[junit4] 2> 1210354 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1210362 INFO (zkConnectionManagerCallback-8134-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1210363 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1210366 WARN (closeThreadPool-8110-thread-1-SendThread(127.0.0.1:61325)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10038ff1daf000a.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf000a, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf000a, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 1210467 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1210467 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-2-001/solr.xml
[junit4] 2> 1210470 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1210525 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1210526 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5c992d3c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1210526 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5c992d3c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1210527 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1c670078[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1210527 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1c670078[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1210530 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61325/solr
[junit4] 2> 1210532 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1210535 INFO (zkConnectionManagerCallback-8144-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1210535 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1210538 WARN (closeThreadPool-8110-thread-1-SendThread(127.0.0.1:61325)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10038ff1daf000b.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf000b, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf000b, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 1210640 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1210644 INFO (zkConnectionManagerCallback-8146-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1210644 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1210657 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1210670 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.c.ZkController Publish node=127.0.0.1:61377_g_oi%2Fmj as DOWN
[junit4] 2> 1210673 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1210674 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61377_g_oi%2Fmj
[junit4] 2> 1210677 INFO (zkCallback-8123-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1210677 INFO (zkCallback-8108-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1210677 INFO (zkCallback-8145-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1210677 INFO (zkCallback-8082-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1210683 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1210683 WARN (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] 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> 1210692 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-3-001 of type NRT for shard2
[junit4] 2> 1210693 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1210693 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1210693 INFO (closeThreadPool-8110-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1210695 INFO (closeThreadPool-8110-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1210695 INFO (closeThreadPool-8110-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1210695 INFO (closeThreadPool-8110-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1210695 INFO (closeThreadPool-8110-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@63454556{/g_oi/mj,null,AVAILABLE}
[junit4] 2> 1210696 INFO (closeThreadPool-8110-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1888d4ef{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61384}
[junit4] 2> 1210696 INFO (closeThreadPool-8110-thread-2) [ ] o.e.j.s.Server Started @1210746ms
[junit4] 2> 1210696 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/g_oi/mj, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/tempDir-001/jetty3, hostPort=61384, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-3-001/cores}
[junit4] 2> 1210697 ERROR (closeThreadPool-8110-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1210697 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1210697 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1210697 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1210697 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 1210697 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-07-31T18:07:55.373465Z
[junit4] 2> 1210698 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1210701 INFO (zkConnectionManagerCallback-8152-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1210701 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1210705 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1210723 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1210731 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1210731 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1210732 INFO (closeThreadPool-8110-thread-1) [n:127.0.0.1:61377_g_oi%2Fmj ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-2-001/cores
[junit4] 2> 1210739 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:61377_g_oi%2Fmj
[junit4] 2> 1210806 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1210806 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-3-001/solr.xml
[junit4] 2> 1210809 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1210961 INFO (OverseerCollectionConfigSetProcessor-72120262403751940-127.0.0.1:61348_g_oi%2Fmj-n_0000000000) [n:127.0.0.1:61348_g_oi%2Fmj ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1211050 INFO (TEST-DistribCursorPagingTest.test-seed#[78B931CDA9BD8371]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-4-001 of type NRT for shard1
[junit4] 2> 1211051 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1211051 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1211051 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1211055 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1211055 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1211055 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1211055 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@41d3d94d{/g_oi/mj,null,AVAILABLE}
[junit4] 2> 1211057 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@91ac174{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61386}
[junit4] 2> 1211057 INFO (closeThreadPool-8110-thread-1) [ ] o.e.j.s.Server Started @1211107ms
[junit4] 2> 1211057 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/g_oi/mj, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/tempDir-001/jetty4, hostPort=61386, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-4-001/cores}
[junit4] 2> 1211058 ERROR (closeThreadPool-8110-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1211058 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1211058 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1211058 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1211058 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 1211058 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-07-31T18:07:55.734910Z
[junit4] 2> 1211059 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1211066 INFO (zkConnectionManagerCallback-8161-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1211066 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1211125 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1211129 WARN (closeThreadPool-8110-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4d62b5ec[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1211130 WARN (closeThreadPool-8110-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4d62b5ec[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1211134 WARN (closeThreadPool-8110-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@35a56872[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1211134 WARN (closeThreadPool-8110-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@35a56872[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1211135 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61325/solr
[junit4] 2> 1211140 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1211143 INFO (zkConnectionManagerCallback-8168-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1211144 INFO (closeThreadPool-8110-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1211149 WARN (closeThreadPool-8110-thread-2-SendThread(127.0.0.1:61325)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10038ff1daf000f.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf000f, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf000f, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 1211186 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1211186 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_78B931CDA9BD8371-001/shard-4-001/solr.xml
[junit4] 2> 1211191 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1211254 INFO (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1211261 INFO (zkConnectionManagerCallback-8173-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1211261 INFO (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1211288 INFO (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1211300 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1211303 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4643be6e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1211305 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4643be6e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1211308 INFO (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] o.a.s.c.ZkController Publish node=127.0.0.1:61384_g_oi%2Fmj as DOWN
[junit4] 2> 1211310 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5ddc7a71[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1211310 WARN (closeThreadPool-8110-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5ddc7a71[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1211310 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61325/solr
[junit4] 2> 1211315 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1211317 INFO (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1211317 INFO (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61384_g_oi%2Fmj
[junit4] 2> 1211319 INFO (zkConnectionManagerCallback-8182-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1211319 INFO (closeThreadPool-8110-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1211321 INFO (zkCallback-8172-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1211321 INFO (zkCallback-8082-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1211322 INFO (zkCallback-8123-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1211322 INFO (zkCallback-8145-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1211322 INFO (zkCallback-8108-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1211324 WARN (closeThreadPool-8110-thread-1-SendThread(127.0.0.1:61325)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10038ff1daf0011.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0011, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10038ff1daf0011, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 1211330 INFO (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1211331 WARN (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] 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> 1211354 INFO (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1211382 INFO (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1211391 INFO (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@347429d8
[junit4] 2> 1211392 INFO (closeThreadPool-8110-thread-2) [n:127.0.0.1:61384_g_oi%2Fmj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeans
[...truncated too long message...]
be5{/solr,null,UNAVAILABLE}
[junit4] 2> 150499 INFO (jetty-closer-1070-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 150589 INFO (jetty-closer-1070-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1de25ba6{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 150589 INFO (jetty-closer-1070-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@21497898{/solr,null,UNAVAILABLE}
[junit4] 2> 150590 INFO (jetty-closer-1070-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 150686 INFO (jetty-closer-1070-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72120339134218248-127.0.0.1:56978_solr-n_0000000000) closing
[junit4] 2> 150690 INFO (jetty-closer-1070-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@20b5591a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 150690 INFO (jetty-closer-1070-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@686393da{/solr,null,UNAVAILABLE}
[junit4] 2> 150692 INFO (jetty-closer-1070-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 150694 INFO (SUITE-StreamDecoratorTest-seed#[2BC0C89C144274C7]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 150696 WARN (SUITE-StreamDecoratorTest-seed#[2BC0C89C144274C7]-worker-SendThread(127.0.0.1:56966)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1003910fb2d0000.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1003910fb2d0000, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1003910fb2d0000, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 150806 WARN (SUITE-StreamDecoratorTest-seed#[2BC0C89C144274C7]-worker-SendThread(127.0.0.1:56966)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1003910fb2d0001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1003910fb2d0001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1003910fb2d0001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 150919 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2> 2 /solr/collections/collection1_collection/terms/shard1
[junit4] 2> 2 /solr/collections/collection1_collection/terms/shard2
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/collections/collection1_collection/state.json
[junit4] 2> 5 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 23 /solr/overseer/queue
[junit4] 2> 19 /solr/live_nodes
[junit4] 2> 10 /solr/collections
[junit4] 2> 7 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 150924 INFO (SUITE-StreamDecoratorTest-seed#[2BC0C89C144274C7]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:56966
[junit4] 2> 150924 INFO (SUITE-StreamDecoratorTest-seed#[2BC0C89C144274C7]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:56966
[junit4] 2> 150924 INFO (SUITE-StreamDecoratorTest-seed#[2BC0C89C144274C7]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 56966
[junit4] 2> 150925 INFO (SUITE-StreamDecoratorTest-seed#[2BC0C89C144274C7]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=28307, maxDocsPerChunk=8, blockShift=17), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=28307, blockSize=17)), sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=se, timezone=America/Regina
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=1,free=153217976,total=285212672
[junit4] 2> NOTE: All tests run in this JVM: [SolrQueryTest, JsonRequestApiHeatmapFacetingTest, GraphTest, TestFastInputStream, StreamExpressionToExplanationTest, HeatmapFacetMapTest, QueryFacetMapTest, LessThanEqualToEvaluatorTest, TestJsonRecordReader, HttpSolrClientConPoolTest, TestTimeSource, TestSolrJErrorHandling, NodePreferenceRulesComparatorTest, SineEvaluatorTest, GetByIdTest, CloudSolrClientMultiConstructorTest, GreaterThanEvaluatorTest, FacetFieldTest, CloudHttp2SolrClientRetryTest, TermsResponseTest, MergeIndexesEmbeddedTest, SolrExampleJettyTest, SolrExampleStreamingHttp2Test, SolrExampleXMLHttp2Test, CloudHttp2SolrClientBuilderTest, CloudSolrClientCacheTest, ConcurrentUpdateHttp2SolrClientTest, HttpSolrClientBuilderTest, GraphExpressionTest, StreamDecoratorTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=StreamDecoratorTest -Dtests.seed=2BC0C89C144274C7 -Dtests.slow=true -Dtests.locale=se -Dtests.timezone=America/Regina -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | StreamDecoratorTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([2BC0C89C144274C7]:0)
[junit4] Completed [127/204 (1!)] on J0 in 5.94s, 0 tests, 1 error <<< FAILURES!
[...truncated 51007 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/6351/consoleText
[repro] Revision: 07a89e7bf627b08b4cef323dd4d187da92b3a411
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=TestRAFDirectory -Dtests.method=testRandomShort -Dtests.seed=5B9917516F04C5E8 -Dtests.slow=true -Dtests.locale=en-SB -Dtests.timezone=Pacific/Wallis -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] Repro line: ant test -Dtestcase=TestRAFDirectory -Dtests.seed=5B9917516F04C5E8 -Dtests.slow=true -Dtests.locale=en-SB -Dtests.timezone=Pacific/Wallis -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] Repro line: ant test -Dtestcase=DistribCursorPagingTest -Dtests.seed=78B931CDA9BD8371 -Dtests.slow=true -Dtests.locale=mt -Dtests.timezone=Asia/Ujung_Pandang -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=StreamDecoratorTest -Dtests.seed=2BC0C89C144274C7 -Dtests.slow=true -Dtests.locale=se -Dtests.timezone=America/Regina -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] lucene/misc
[repro] TestRAFDirectory
[repro] solr/core
[repro] DistribCursorPagingTest
[repro] solr/solrj
[repro] StreamDecoratorTest
[repro] ant compile-test
[...truncated 155 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestRAFDirectory" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=5B9917516F04C5E8 -Dtests.slow=true -Dtests.locale=en-SB -Dtests.timezone=Pacific/Wallis -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 296 lines...]
[repro] ant compile-test
[...truncated 2390 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.DistribCursorPagingTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=78B931CDA9BD8371 -Dtests.slow=true -Dtests.locale=mt -Dtests.timezone=Asia/Ujung_Pandang -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 86 lines...]
[repro] ant compile-test
[...truncated 451 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.StreamDecoratorTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=2BC0C89C144274C7 -Dtests.slow=true -Dtests.locale=se -Dtests.timezone=America/Regina -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 361 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.lucene.store.TestRAFDirectory
[repro] 0/5 failed: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[repro] 0/5 failed: org.apache.solr.cloud.DistribCursorPagingTest
[repro] Exiting with code 0
[...truncated 79 lines...]
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk-14.0.1) - Build #
6352 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/6352/
Java: 64bit/jdk-14.0.1 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedGrouping.test
Error Message:
Error from server at http://127.0.0.1:53040/_/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:6/_, http://[::1]:8/_, http://[::1]:4/_]
Stack Trace:
org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:53040/_/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:6/_, http://[::1]:8/_, http://[::1]:4/_]
at __randomizedtesting.SeedInfo.seed([C684F580DA930694:4ED0CA5A746F6B6C]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:210)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1004)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1019)
at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:625)
at org.apache.solr.TestDistributedGrouping.simpleQuery(TestDistributedGrouping.java:455)
at org.apache.solr.TestDistributedGrouping.test(TestDistributedGrouping.java:443)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1117)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
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:832)
Build Log:
[...truncated 13238 lines...]
[junit4] Suite: org.apache.solr.TestDistributedGrouping
[junit4] 2> 744086 INFO (SUITE-TestDistributedGrouping-seed#[C684F580DA930694]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 744086 INFO (SUITE-TestDistributedGrouping-seed#[C684F580DA930694]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/data-dir-101-001
[junit4] 2> 744087 WARN (SUITE-TestDistributedGrouping-seed#[C684F580DA930694]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 744087 INFO (SUITE-TestDistributedGrouping-seed#[C684F580DA930694]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 744088 INFO (SUITE-TestDistributedGrouping-seed#[C684F580DA930694]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 744088 INFO (SUITE-TestDistributedGrouping-seed#[C684F580DA930694]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 744088 INFO (SUITE-TestDistributedGrouping-seed#[C684F580DA930694]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_/
[junit4] 2> 744410 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/control/cores/collection1
[junit4] 2> 744411 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 744412 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 744412 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 744415 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 744415 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 744415 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 744416 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@360d054{/_,null,AVAILABLE}
[junit4] 2> 744418 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@31ae9fff{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:52980}
[junit4] 2> 744418 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.Server Started @744468ms
[junit4] 2> 744418 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_, hostPort=52980, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/control/cores}
[junit4] 2> 744418 ERROR (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 744418 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 744418 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 744418 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 744418 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 744418 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-07-31T22:58:06.225749Z
[junit4] 2> 744418 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/control/solr.xml
[junit4] 2> 744423 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@120db52e, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 744581 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 744583 WARN (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@602e74c5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 744583 WARN (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@602e74c5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 744588 WARN (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7a1157d7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 744589 WARN (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7a1157d7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 744590 WARN (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] 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> 744623 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 744623 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 744646 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@120db52e
[junit4] 2> 744655 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@120db52e
[junit4] 2> 744655 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@120db52e
[junit4] 2> 744657 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/control/cores
[junit4] 2> 744657 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 744661 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 744679 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 744685 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 744763 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 744773 INFO (coreLoadExecutor-6029-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@120db52e
[junit4] 2> 744773 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/control/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 744774 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4964444934240536]
[junit4] 2> 744868 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 744868 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 744882 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 744883 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 744883 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=36.81640625, floorSegmentMB=1.11328125, forceMergeDeletesPctAllowed=6.5292512129147475, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=48.316593491399416
[junit4] 2> 744885 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 744918 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=lowerfilt, minQueryLength=3}
[junit4] 2> 745082 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 745084 INFO (coreLoadExecutor-6029-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1673775060763017216
[junit4] 2> 745084 INFO (searcherExecutor-6031-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 745085 INFO (searcherExecutor-6031-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 745085 INFO (searcherExecutor-6031-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 745085 INFO (searcherExecutor-6031-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 745085 INFO (searcherExecutor-6031-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 745086 INFO (searcherExecutor-6031-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 745086 INFO (searcherExecutor-6031-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 745087 INFO (searcherExecutor-6031-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 745087 INFO (searcherExecutor-6031-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 745088 INFO (searcherExecutor-6031-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 745504 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 745505 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 745505 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 745505 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 745515 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 745515 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 745515 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 745516 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a225ec7{/_,null,AVAILABLE}
[junit4] 2> 745517 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@719d82e9{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:52993}
[junit4] 2> 745517 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.s.Server Started @745567ms
[junit4] 2> 745517 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_, hostPort=52993, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/shard0/cores}
[junit4] 2> 745517 ERROR (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 745517 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 745517 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 745517 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 745517 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 745517 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-07-31T22:58:07.324668Z
[junit4] 2> 745517 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/shard0/solr.xml
[junit4] 2> 745520 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@120db52e, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 745594 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 745595 WARN (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1f384ca[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 745595 WARN (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1f384ca[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 745598 WARN (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4c3ef530[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 745598 WARN (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4c3ef530[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 745599 WARN (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] 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> 745615 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 745615 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 745631 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@120db52e
[junit4] 2> 745641 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@120db52e
[junit4] 2> 745641 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@120db52e
[junit4] 2> 745642 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/shard0/cores
[junit4] 2> 745643 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 745654 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 745671 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 745674 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 745746 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 745758 INFO (coreLoadExecutor-6047-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@120db52e
[junit4] 2> 745758 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/shard0/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 745761 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4964444934240536]
[junit4] 2> 745827 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 745827 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 745828 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 745828 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 745829 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=36.81640625, floorSegmentMB=1.11328125, forceMergeDeletesPctAllowed=6.5292512129147475, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=48.316593491399416
[junit4] 2> 745830 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 745860 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=lowerfilt, minQueryLength=3}
[junit4] 2> 745980 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 745980 INFO (coreLoadExecutor-6047-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1673775061702541312
[junit4] 2> 745981 INFO (searcherExecutor-6049-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 745982 INFO (searcherExecutor-6049-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 745982 INFO (searcherExecutor-6049-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 745982 INFO (searcherExecutor-6049-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 745983 INFO (searcherExecutor-6049-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 745983 INFO (searcherExecutor-6049-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 745984 INFO (TEST-TestDistributedGrouping.test-seed#[C684F580DA930694]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 745984 INFO (searcherExecutor-6049-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 745985 INFO (searcherExecutor-6049-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 745986 INFO (searcherExecutor-6049-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 745987 INFO (searcherExecutor-6049-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 745991 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1673775061709881344)} 0 4
[junit4] 2> 745997 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1673775061717221376)} 0 2
[junit4] 2> 745998 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 0
[junit4] 2> 746000 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 0
[junit4] 2> 746005 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={q=*:*&distrib=false&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} hits=0 status=0 QTime=2
[junit4] 2> 746024 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:52993/_/collection1|[::1]:6/_|[::1]:8/_|[::1]:4/_&rows=100&rid=127.0.0.1-121608&version=2&q=*:*&omitHeader=false&NOW=1596236287816&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=5
[junit4] 2> 746031 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={q=*:*&shards=http://127.0.0.1:52993/_/collection1|[::1]:6/_|[::1]:8/_|[::1]:4/_&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} rid=127.0.0.1-121608 status=0 QTime=22
[junit4] 2> 746033 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={q=*:*&distrib=false&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} hits=0 status=0 QTime=0
[junit4] 2> 746036 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={df=text&group.distributed.first=true&distrib=false&hl=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:52993/_/collection1|[::1]:8/_&rows=100&rid=127.0.0.1-121610&version=2&q=*:*&omitHeader=false&NOW=1596236287841&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 746038 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={q=*:*&shards=http://127.0.0.1:52993/_/collection1|[::1]:8/_&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} rid=127.0.0.1-121610 status=0 QTime=3
[junit4] 2> 746040 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={q=*:*&facet.field=a_t&distrib=false&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true} hits=0 status=0 QTime=1
[junit4] 2> 746045 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[::1]:6/_|http://127.0.0.1:52993/_/collection1|[::1]:4/_|[::1]:8/_&rows=100&rid=127.0.0.1-121612&version=2&q=*:*&omitHeader=false&NOW=1596236287848&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 746050 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&shard.url=[::1]:6/_|http://127.0.0.1:52993/_/collection1|[::1]:4/_|[::1]:8/_&rows=100&rid=127.0.0.1-121612&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&omitHeader=false&NOW=1596236287848&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=3
[junit4] 2> 746052 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={q=*:*&shards=[::1]:6/_|http://127.0.0.1:52993/_/collection1|[::1]:4/_|[::1]:8/_&facet.field=a_t&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true} rid=127.0.0.1-121612 status=0 QTime=10
[junit4] 2> 746054 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={q=*:*&distrib=false&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1} hits=0 status=0 QTime=0
[junit4] 2> 746056 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:52993/_/collection1&rows=100&rid=127.0.0.1-121614&version=2&q=*:*&stats=false&omitHeader=false&NOW=1596236287862&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0
[junit4] 2> 746058 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=516&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:52993/_/collection1&rows=100&rid=127.0.0.1-121614&version=2&group.distributed.second=true&q=*:*&stats=true&omitHeader=false&NOW=1596236287862&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0
[junit4] 2> 746059 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={q=*:*&shards=http://127.0.0.1:52993/_/collection1&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1} rid=127.0.0.1-121614 status=0 QTime=4
[junit4] 2> 746061 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={df=subject&distrib=false&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&wt=javabin&group.field=a_i1&group=true} hits=0 status=0 QTime=0
[junit4] 2> 746064 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={df=subject&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:52993/_/collection1&rows=100&rid=127.0.0.1-121616&version=2&spellcheck.build=true&q=kings&spellcheck=true&omitHeader=false&NOW=1596236287869&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 746065 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={df=subject&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&shards=http://127.0.0.1:52993/_/collection1&spellcheck=true&wt=javabin&group.field=a_i1&group=true} rid=127.0.0.1-121616 status=0 QTime=2
[junit4] 2> 746068 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={facet.field=a_t&distrib=false&fl=id,a_i1&group.limit=-1&fq=a_s:a&sort=a_i1+asc,+id+asc&rows=100&version=2&q=*:*&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true} hits=0 status=0 QTime=2
[junit4] 2> 746070 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=[::1]:6/_|http://127.0.0.1:52993/_/collection1&rows=100&rid=127.0.0.1-121618&version=2&q=*:*&group.truncate=true&omitHeader=false&NOW=1596236287876&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 746072 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={df=text&distrib=false&fl=id&shards.purpose=20&fq=a_s:a&shard.url=[::1]:6/_|http://127.0.0.1:52993/_/collection1&rid=127.0.0.1-121618&wt=javabin&group.field=a_i1&group=true&facet.field=a_t&group.limit=-1&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&group.truncate=true&omitHeader=false&NOW=1596236287876&isShard=true&facet=true} status=0 QTime=0
[junit4] 2> 746073 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/_ path=/select params={facet.field=a_t&fl=id,a_i1&group.limit=-1&fq=a_s:a&sort=a_i1+asc,+id+asc&rows=100&version=2&q=*:*&shards=[::1]:6/_|http://127.0.0.1:52993/_/collection1&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true} rid=127.0.0.1-121618 status=0 QTime=4
[junit4] 2> 746077 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[1 (1673775061802156032)]} 0 2
[junit4] 2> 746079 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[1 (1673775061805301760)]} 0 1
[junit4] 2> 746081 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[2 (1673775061807398912)]} 0 0
[junit4] 2> 746082 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[2 (1673775061809496064)]} 0 0
[junit4] 2> 746083 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[3 (1673775061809496064)]} 0 0
[junit4] 2> 746084 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[3 (1673775061811593216)]} 0 0
[junit4] 2> 746085 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[4 (1673775061812641792)]} 0 0
[junit4] 2> 746086 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[4 (1673775061813690368)]} 0 0
[junit4] 2> 746086 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[5 (1673775061813690368)]} 0 0
[junit4] 2> 746086 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[5 (1673775061813690369)]} 0 0
[junit4] 2> 746092 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[6 (1673775061819981824)]} 0 0
[junit4] 2> 746094 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[6 (1673775061821030400)]} 0 0
[junit4] 2> 746095 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[7 (1673775061823127552)]} 0 0
[junit4] 2> 746096 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[7 (1673775061824176128)]} 0 0
[junit4] 2> 746097 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[8 (1673775061825224704)]} 0 0
[junit4] 2> 746099 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[8 (1673775061826273280)]} 0 0
[junit4] 2> 746100 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[9 (1673775061827321856)]} 0 0
[junit4] 2> 746101 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[9 (1673775061829419008)]} 0 0
[junit4] 2> 746102 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[10 (1673775061830467584)]} 0 0
[junit4] 2> 746104 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[10 (1673775061831516160)]} 0 1
[junit4] 2> 746105 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[11 (1673775061833613312)]} 0 0
[junit4] 2> 746107 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[11 (1673775061835710464)]} 0 0
[junit4] 2> 746108 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[12 (1673775061836759040)]} 0 0
[junit4] 2> 746109 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[12 (1673775061837807616)]} 0 0
[junit4] 2> 746112 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[14 (1673775061839904768)]} 0 0
[junit4] 2> 746114 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[14 (1673775061842001920)]} 0 1
[junit4] 2> 746117 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[15 (1673775061846196224)]} 0 0
[junit4] 2> 746117 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[15 (1673775061846196224)]} 0 0
[junit4] 2> 746122 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[16 (1673775061846196225)]} 0 4
[junit4] 2> 746125 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[16 (1673775061852487680)]} 0 2
[junit4] 2> 746127 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[17 (1673775061856681984)]} 0 0
[junit4] 2> 746130 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[17 (1673775061859827712)]} 0 0
[junit4] 2> 746133 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[18 (1673775061861924864)]} 0 0
[junit4] 2> 746137 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[18 (1673775061866119168)]} 0 0
[junit4] 2> 746138 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[19 (1673775061868216320)]} 0 0
[junit4] 2> 746139 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[19 (1673775061869264896)]} 0 0
[junit4] 2> 746143 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[20 (1673775061872410624)]} 0 1
[junit4] 2> 746145 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[20 (1673775061875556352)]} 0 0
[junit4] 2> 746147 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[21 (1673775061877653504)]} 0 0
[junit4] 2> 746148 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[21 (1673775061878702080)]} 0 0
[junit4] 2> 746150 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[22 (1673775061879750656)]} 0 0
[junit4] 2> 746151 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[22 (1673775061881847808)]} 0 0
[junit4] 2> 746153 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[23 (1673775061882896384)]} 0 0
[junit4] 2> 746154 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[23 (1673775061884993536)]} 0 0
[junit4] 2> 746156 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[100 (1673775061887090688)]} 0 0
[junit4] 2> 746158 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[100 (1673775061889187840)]} 0 0
[junit4] 2> 746159 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[101 (1673775061890236416)]} 0 0
[junit4] 2> 746161 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[101 (1673775061892333568)]} 0 0
[junit4] 2> 746164 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[102 (1673775061895479296)]} 0 0
[junit4] 2> 746166 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[102 (1673775061897576448)]} 0 0
[junit4] 2> 746167 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[103 (1673775061898625024)]} 0 0
[junit4] 2> 746169 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[103 (1673775061899673600)]} 0 0
[junit4] 2> 746169 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[104 (1673775061900722176)]} 0 0
[junit4] 2> 746172 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[104 (1673775061902819328)]} 0 0
[junit4] 2> 746174 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[105 (1673775061905965056)]} 0 0
[junit4] 2> 746176 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[105 (1673775061907013632)]} 0 0
[junit4] 2> 746177 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[106 (1673775061909110784)]} 0 0
[junit4] 2> 746178 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[106 (1673775061910159360)]} 0 0
[junit4] 2> 746179 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[107 (1673775061911207936)]} 0 0
[junit4] 2> 746180 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[107 (1673775061912256512)]} 0 0
[junit4] 2> 746181 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[108 (1673775061913305088)]} 0 0
[junit4] 2> 746182 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[108 (1673775061914353664)]} 0 0
[junit4] 2> 746182 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[109 (1673775061914353664)]} 0 0
[junit4] 2> 746184 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[109 (1673775061915402240)]} 0 0
[junit4] 2> 746185 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[110 (1673775061916450816)]} 0 0
[junit4] 2> 746187 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[110 (1673775061918547968)]} 0 0
[junit4] 2> 746188 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[111 (1673775061920645120)]} 0 0
[junit4] 2> 746190 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[111 (1673775061921693696)]} 0 0
[junit4] 2> 746190 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[112 (1673775061922742272)]} 0 0
[junit4] 2> 746192 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[112 (1673775061924839424)]} 0 0
[junit4] 2> 746194 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[113 (1673775061925888000)]} 0 0
[junit4] 2> 746195 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[113 (1673775061926936576)]} 0 0
[junit4] 2> 746196 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[114 (1673775061927985152)]} 0 0
[junit4] 2> 746196 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[114 (1673775061929033728)]} 0 0
[junit4] 2> 746197 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[115 (1673775061930082304)]} 0 0
[junit4] 2> 746200 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[115 (1673775061931130880)]} 0 1
[junit4] 2> 746204 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[116 (1673775061936373760)]} 0 0
[junit4] 2> 746205 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[116 (1673775061937422336)]} 0 0
[junit4] 2> 746205 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[117 (1673775061938470912)]} 0 0
[junit4] 2> 746206 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[117 (1673775061939519488)]} 0 0
[junit4] 2> 746208 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[118 (1673775061940568064)]} 0 0
[junit4] 2> 746208 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[118 (1673775061941616640)]} 0 0
[junit4] 2> 746212 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[119 (1673775061945810944)]} 0 0
[junit4] 2> 746213 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[119 (1673775061946859520)]} 0 0
[junit4] 2> 746215 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[120 (1673775061947908096)]} 0 0
[junit4] 2> 746216 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[120 (1673775061948956672)]} 0 0
[junit4] 2> 746217 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[121 (1673775061950005248)]} 0 0
[junit4] 2> 746218 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[121 (1673775061952102400)]} 0 0
[junit4] 2> 746220 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[122 (1673775061954199552)]} 0 0
[junit4] 2> 746222 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[122 (1673775061956296704)]} 0 0
[junit4] 2> 746223 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[123 (1673775061956296704)]} 0 0
[junit4] 2> 746224 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[123 (1673775061957345280)]} 0 0
[junit4] 2> 746224 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[124 (1673775061958393856)]} 0 0
[junit4] 2> 746229 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[124 (1673775061963636736)]} 0 0
[junit4] 2> 746231 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[125 (1673775061965733888)]} 0 0
[junit4] 2> 746232 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[125 (1673775061966782464)]} 0 0
[junit4] 2> 746234 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[126 (1673775061968879616)]} 0 0
[junit4] 2> 746237 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[126 (1673775061970976768)]} 0 0
[junit4] 2> 746238 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[127 (1673775061972025344)]} 0 0
[junit4] 2> 746247 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[127 (1673775061975171072)]} 0 7
[junit4] 2> 746252 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[128 (1673775061987753984)]} 0 2
[junit4] 2> 746254 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[128 (1673775061989851136)]} 0 0
[junit4] 2> 746258 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[129 (1673775061992996864)]} 0 0
[junit4] 2> 746260 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[129 (1673775061996142592)]} 0 0
[junit4] 2> 746262 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[130 (1673775061998239744)]} 0 0
[junit4] 2> 746263 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[130 (1673775061999288320)]} 0 0
[junit4] 2> 746264 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[131 (1673775062000336896)]} 0 0
[junit4] 2> 746265 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[131 (1673775062001385472)]} 0 0
[junit4] 2> 746267 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[132 (1673775062003482624)]} 0 0
[junit4] 2> 746269 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[132 (1673775062005579776)]} 0 0
[junit4] 2> 746271 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[133 (1673775062007676928)]} 0 0
[junit4] 2> 746272 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[133 (1673775062008725504)]} 0 0
[junit4] 2> 746276 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[134 (1673775062012919808)]} 0 0
[junit4] 2> 746278 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[134 (1673775062013968384)]} 0 0
[junit4] 2> 746285 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[135 (1673775062021308416)]} 0 0
[junit4] 2> 746286 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[135 (1673775062023405568)]} 0 0
[junit4] 2> 746288 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[136 (1673775062025502720)]} 0 0
[junit4] 2> 746291 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[136 (1673775062027599872)]} 0 0
[junit4] 2> 746292 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[137 (1673775062029697024)]} 0 0
[junit4] 2> 746294 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[137 (1673775062031794176)]} 0 0
[junit4] 2> 746295 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[138 (1673775062032842752)]} 0 0
[junit4] 2> 746297 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[138 (1673775062034939904)]} 0 0
[junit4] 2> 746298 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[139 (1673775062035988480)]} 0 0
[junit4] 2> 746300 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[139 (1673775062037037056)]} 0 0
[junit4] 2> 746301 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[140 (1673775062039134208)]} 0 0
[junit4] 2> 746303 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[140 (1673775062040182784)]} 0 0
[junit4] 2> 746305 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[141 (1673775062042279936)]} 0 0
[junit4] 2> 746307 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[141 (1673775062044377088)]} 0 0
[junit4] 2> 746308 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[142 (1673775062046474240)]} 0 0
[junit4] 2> 746310 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[142 (1673775062048571392)]} 0 0
[junit4] 2> 746312 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[143 (1673775062050668544)]} 0 0
[junit4] 2> 746314 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[143 (1673775062051717120)]} 0 0
[junit4] 2> 746316 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[144 (1673775062054862848)]} 0 0
[junit4] 2> 746318 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[144 (1673775062056960000)]} 0 0
[junit4] 2> 746319 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[145 (1673775062058008576)]} 0 0
[junit4] 2> 746323 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[145 (1673775062061154304)]} 0 0
[junit4] 2> 746325 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[146 (1673775062063251456)]} 0 0
[junit4] 2> 746325 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[146 (1673775062064300032)]} 0 0
[junit4] 2> 746331 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[147 (1673775062070591488)]} 0 0
[junit4] 2> 746334 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[147 (1673775062073737216)]} 0 0
[junit4] 2> 746336 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[148 (1673775062074785792)]} 0 0
[junit4] 2> 746338 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[148 (1673775062076882944)]} 0 0
[junit4] 2> 746339 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[149 (1673775062078980096)]} 0 0
[junit4] 2> 746341 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[149 (1673775062080028672)]} 0 0
[junit4] 2> 746343 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[500 (1673775062082125824)]} 0 0
[junit4] 2> 746345 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[500 (1673775062084222976)]} 0 1
[junit4] 2> 746347 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[501 (1673775062087368704)]} 0 0
[junit4] 2> 746348 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[501 (1673775062088417280)]} 0 0
[junit4] 2> 746349 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[502 (1673775062089465856)]} 0 0
[junit4] 2> 746351 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[502 (1673775062091563008)]} 0 0
[junit4] 2> 746352 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[503 (1673775062092611584)]} 0 0
[junit4] 2> 746353 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[503 (1673775062093660160)]} 0 0
[junit4] 2> 746354 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[504 (1673775062094708736)]} 0 0
[junit4] 2> 746355 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[504 (1673775062095757312)]} 0 0
[junit4] 2> 746357 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[505 (1673775062096805888)]} 0 0
[junit4] 2> 746358 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[505 (1673775062098903040)]} 0 0
[junit4] 2> 746360 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[506 (1673775062101000192)]} 0 0
[junit4] 2> 746362 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[506 (1673775062102048768)]} 0 0
[junit4] 2> 746368 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[507 (1673775062108340224)]} 0 0
[junit4] 2> 746370 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[507 (1673775062110437376)]} 0 1
[junit4] 2> 746371 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[508 (1673775062112534528)]} 0 0
[junit4] 2> 746373 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[508 (1673775062114631680)]} 0 0
[junit4] 2> 746376 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[509 (1673775062116728832)]} 0 0
[junit4] 2> 746377 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[509 (1673775062118825984)]} 0 0
[junit4] 2> 746378 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[510 (1673775062119874560)]} 0 0
[junit4] 2> 746380 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[510 (1673775062121971712)]} 0 0
[junit4] 2> 746385 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[511 (1673775062126166016)]} 0 0
[junit4] 2> 746386 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[511 (1673775062128263168)]} 0 0
[junit4] 2> 746388 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[512 (1673775062130360320)]} 0 0
[junit4] 2> 746389 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[512 (1673775062131408896)]} 0 0
[junit4] 2> 746390 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[513 (1673775062132457472)]} 0 0
[junit4] 2> 746390 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[513 (1673775062132457472)]} 0 0
[junit4] 2> 746396 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[514 (1673775062137700352)]} 0 6
[junit4] 2> 746399 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[514 (1673775062141894656)]} 0 0
[junit4] 2> 746403 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[515 (1673775062145040384)]} 0 0
[junit4] 2> 746406 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[515 (1673775062149234688)]} 0 0
[junit4] 2> 746409 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[516 (1673775062152380416)]} 0 0
[junit4] 2> 746412 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[516 (1673775062154477568)]} 0 0
[junit4] 2> 746414 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[517 (1673775062157623296)]} 0 0
[junit4] 2> 746416 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[517 (1673775062159720448)]} 0 0
[junit4] 2> 746418 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[518 (1673775062161817600)]} 0 0
[junit4] 2> 746419 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[518 (1673775062161817600)]} 0 0
[junit4] 2> 746422 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[519 (1673775062166011904)]} 0 0
[junit4] 2> 746423 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[519 (1673775062167060480)]} 0 0
[junit4] 2> 746425 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[520 (1673775062168109056)]} 0 0
[junit4] 2> 746428 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[520 (1673775062172303360)]} 0 0
[junit4] 2> 746431 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[521 (1673775062174400512)]} 0 0
[junit4] 2> 746443 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[521 (1673775062186983424)]} 0 0
[junit4] 2> 746446 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[522 (1673775062191177728)]} 0 0
[junit4] 2> 746447 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[522 (1673775062192226304)]} 0 0
[junit4] 2> 746452 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[523 (1673775062197469184)]} 0 0
[junit4] 2> 746458 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[523 (1673775062203760640)]} 0 0
[junit4] 2> 746460 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[524 (1673775062205857792)]} 0 0
[junit4] 2> 746468 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[524 (1673775062213197824)]} 0 0
[junit4] 2> 746470 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[525 (1673775062216343552)]} 0 0
[junit4] 2> 746484 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[525 (1673775062231023616)]} 0 0
[junit4] 2> 746485 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[526 (1673775062232072192)]} 0 0
[junit4] 2> 746488 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[526 (1673775062234169344)]} 0 0
[junit4] 2> 746491 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[527 (1673775062238363648)]} 0 0
[junit4] 2> 746496 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[527 (1673775062241509376)]} 0 1
[junit4] 2> 746498 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[528 (1673775062245703680)]} 0 0
[junit4] 2> 746503 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[528 (1673775062250946560)]} 0 1
[junit4] 2> 746505 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[529 (1673775062253043712)]} 0 0
[junit4] 2> 746507 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[529 (1673775062255140864)]} 0 0
[junit4] 2> 746508 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[530 (1673775062256189440)]} 0 0
[junit4] 2> 746510 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[530 (1673775062258286592)]} 0 0
[junit4] 2> 746513 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[531 (1673775062260383744)]} 0 0
[junit4] 2> 746516 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[531 (1673775062263529472)]} 0 0
[junit4] 2> 746524 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[532 (1673775062272966656)]} 0 0
[junit4] 2> 746526 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[532 (1673775062275063808)]} 0 0
[junit4] 2> 746527 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[533 (1673775062276112384)]} 0 0
[junit4] 2> 746528 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[533 (1673775062277160960)]} 0 0
[junit4] 2> 746530 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[534 (1673775062278209536)]} 0 0
[junit4] 2> 746532 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[534 (1673775062281355264)]} 0 0
[junit4] 2> 746535 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[535 (1673775062284500992)]} 0 0
[junit4] 2> 746539 INFO (qtp1209676646-8854) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[535 (1673775062287646720)]} 0 0
[junit4] 2> 746546 INFO (qtp1066158521-8833) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[536 (1673775062296035328)]} 0 0
[junit4] 2> 746548 INFO (qtp1209676646-8855) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[536 (1673775062298132480)]} 0 0
[junit4] 2> 746550 INFO (qtp1066158521-8834) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[537 (1673775062300229632)]} 0 0
[junit4] 2> 746551 INFO (qtp1209676646-8853) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[537 (1673775062301278208)]} 0 0
[junit4] 2> 746555 INFO (qtp1066158521-8835) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[538 (1673775062305472512)]} 0 0
[junit4] 2> 746556 INFO (qtp1209676646-8856) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_ path=/update params={wt=javabin&version=2}{add=[538 (1673775062306521088)]} 0 0
[junit4] 2> 746558 INFO (qtp1066158521-8832) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFact
[...truncated too long message...]
[junit4] 2> 757351 INFO (closeThreadPool-6120-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 757351 INFO (closeThreadPool-6120-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14489e11: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@711636f9
[junit4] 2> 757355 INFO (closeThreadPool-6120-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1d832737{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 757356 INFO (closeThreadPool-6120-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@68720718{/_,null,UNAVAILABLE}
[junit4] 2> 757356 INFO (closeThreadPool-6120-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 757359 INFO (closeThreadPool-6120-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@17ac057{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 757359 INFO (closeThreadPool-6120-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@721ce39e{/_,null,UNAVAILABLE}
[junit4] 2> 757359 INFO (closeThreadPool-6120-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 757365 INFO (coreCloseExecutor-6129-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 757367 INFO (closeThreadPool-6120-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 757367 INFO (closeThreadPool-6120-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b372fde: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@56e94a4
[junit4] 2> 757370 INFO (closeThreadPool-6120-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 757370 INFO (closeThreadPool-6120-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@24f94cd8: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@7a589ed3
[junit4] 2> 757372 INFO (closeThreadPool-6120-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 757372 INFO (closeThreadPool-6120-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@23b06459: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@711636f9
[junit4] 2> 757376 INFO (closeThreadPool-6120-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@39ae1b6e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 757376 INFO (closeThreadPool-6120-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@79c41196{/_,null,UNAVAILABLE}
[junit4] 2> 757376 INFO (closeThreadPool-6120-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedGrouping -Dtests.method=test -Dtests.seed=C684F580DA930694 -Dtests.slow=true -Dtests.locale=und -Dtests.timezone=America/Hermosillo -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 13.3s J1 | TestDistributedGrouping.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:53040/_/collection1: org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this request exceeded:[http://[::1]:6/_, http://[::1]:8/_, http://[::1]:4/_]
[junit4] > at __randomizedtesting.SeedInfo.seed([C684F580DA930694:4ED0CA5A746F6B6C]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:210)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1004)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1019)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:625)
[junit4] > at org.apache.solr.TestDistributedGrouping.simpleQuery(TestDistributedGrouping.java:455)
[junit4] > at org.apache.solr.TestDistributedGrouping.test(TestDistributedGrouping.java:443)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1117)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.TestDistributedGrouping_C684F580DA930694-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86), sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=und, timezone=America/Hermosillo
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 14.0.1 (64-bit)/cpus=6,threads=2,free=100899304,total=233246720
[junit4] 2> NOTE: All tests run in this JVM: [SolrSlf4jReporterTest, TestLogWatcher, TestWriterPerf, DeleteStatusTest, PropertiesRequestHandlerTest, RankQueryTest, ClusterStateTest, TestGraphTermsQParserPlugin, DimensionalRoutedAliasUpdateProcessorTest, DistributedDebugComponentTest, SolrCoreCheckLockOnStartupTest, HdfsSyncSliceTest, TestDFISimilarityFactory, RegexBoostProcessorTest, ZkCLITest, TestXIncludeConfig, DateRangeFieldTest, TestTrackingShardHandlerFactory, TestClusterProperties, TestRealTimeGet, DirectSolrSpellCheckerTest, CdcrUpdateLogTest, TestOverriddenPrefixQueryForCustomFieldType, TestQueryUtils, TestLuceneIndexBackCompat, TestTextField, TestFaceting, BasicDistributedZk2Test, HdfsChaosMonkeySafeLeaderTest, HLLSerializationTest, BooleanFieldTest, TestSSLRandomization, ConnectionReuseTest, TestLocalFSCloudBackupRestore, TestTrieFacet, RangeFacetCloudTest, SimpleCollectionCreateDeleteTest, TestObjectReleaseTracker, TestInPlaceUpdatesDistrib, TestStressReorder, RollingRestartTest, TimeRoutedAliasUpdateProcessorTest, DateMathParserTest, TestRSAKeyPair, HdfsLockFactoryTest, TermsComponentTest, Tagger2Test, TestDeleteCollectionOnDownNodes, TestComplexPhraseQParserPlugin, StatelessScriptUpdateProcessorFactoryTest, TestRandomDVFaceting, MoreLikeThisHandlerTest, TestFieldCollectionResource, TestExactSharedStatsCacheCloud, AdminHandlersProxyTest, StatsComponentTest, TestCSVLoader, TestInitParams, TestQuerySenderListener, SchemaVersionSpecificBehaviorTest, TestBlendedInfixSuggestions, CdcrWithNodesRestartsTest, ShardRoutingTest, TestLeaderElectionWithEmptyReplica, BigEndianAscendingWordDeserializerTest, MoveReplicaHDFSTest, TestRestManager, TestJavabinTupleStreamParser, TestNumericTerms32, TestUpdate, TestConfig, AddSchemaFieldsUpdateProcessorFactoryTest, QueryResultKeyTest, TestWaitForStateWithJettyShutdowns, TestSystemCollAutoCreate, TestPackages, AnalyticsQueryTest, TestHalfAndHalfDocValues, NumericFieldsTest, TestBinaryResponseWriter, SolrIndexSplitterTest, JWTVerificationkeyResolverTest, HttpPartitionOnCommitTest, PeerSyncTest, AnalysisAfterCoreReloadTest, BasicFunctionalityTest, ConvertedLegacyTest, DistributedIntervalFacetingTest, EchoParamsTest, MinimalSchemaTest, OutputWriterTest, SampleTest, SolrInfoBeanTest, SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, TestDistributedGrouping]
[junit4] Completed [342/868 (1!)] on J1 in 13.31s, 1 test, 1 error <<< FAILURES!
[...truncated 46474 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/6352/consoleText
[repro] Revision: 07a89e7bf627b08b4cef323dd4d187da92b3a411
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestDistributedGrouping -Dtests.method=test -Dtests.seed=C684F580DA930694 -Dtests.slow=true -Dtests.locale=und -Dtests.timezone=America/Hermosillo -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestDistributedGrouping
[repro] ant compile-test
[...truncated 2446 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDistributedGrouping" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=C684F580DA930694 -Dtests.slow=true -Dtests.locale=und -Dtests.timezone=America/Hermosillo -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 81 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.TestDistributedGrouping
[repro] Exiting with code 0
[...truncated 79 lines...]