Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.11/736/
1 tests failed.
FAILED:
org.apache.solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest.testAsyncRequests
Error Message:
AddReplica did not complete expected same:<COMPLETED> was not:<RUNNING>
Stack Trace:
java.lang.AssertionError: AddReplica did not complete expected same:<COMPLETED>
was not:<RUNNING>
at
__randomizedtesting.SeedInfo.seed([3BF5A92D2F4D9080:DFB1959A89E5DE5F]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.failNotSame(Assert.java:829)
at org.junit.Assert.assertSame(Assert.java:772)
at
org.apache.solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest.testAsyncRequests(CollectionsAPIAsyncDistributedZkTest.java:162)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1758)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:946)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:982)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:996)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:80)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:390)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:843)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:490)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:955)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:840)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:891)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:902)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:80)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:390)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:850)
at java.lang.Thread.run(Thread.java:750)
Build Log:
[...truncated 14560 lines...]
[junit4] Suite:
org.apache.solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest
[junit4] 2> 757963 INFO
(SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[3BF5A92D2F4D9080]-worker) [
] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to
test-framework derived value of
'/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/server/solr/configsets/_default/conf'
[junit4] 2> 757963 INFO
(SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[3BF5A92D2F4D9080]-worker) [
] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-254 after mutting 0 log
messages
[junit4] 2> 757963 INFO
(SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[3BF5A92D2F4D9080]-worker) [
] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-255 for ERROR logs
matching regex: ignore_exception
[junit4] 2> 757964 INFO
(SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[3BF5A92D2F4D9080]-worker) [
] o.a.s.SolrTestCaseJ4 Created dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_3BF5A92D2F4D9080-001/data-dir-75-001
[junit4] 2> 757964 INFO
(SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[3BF5A92D2F4D9080]-worker) [
] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 757965 INFO
(SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[3BF5A92D2F4D9080]-worker) [
] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 757965 INFO
(SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[3BF5A92D2F4D9080]-worker) [
] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 757968 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testAsyncRequests
[junit4] 2> 757969 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_3BF5A92D2F4D9080-001/tempDir-001
[junit4] 2> 757969 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 757969 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 757969 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 757970 WARN (ZkTestServer Run Thread) [ ]
o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 758069 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.ZkTestServer start zk server on port: 38225
[junit4] 2> 758070 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:38225
[junit4] 2> 758070 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38225
[junit4] 2> 758070 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38225
[junit4] 2> 758072 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 758074 INFO (zkConnectionManagerCallback-8737-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 758074 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 758079 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 758084 INFO (zkConnectionManagerCallback-8739-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 758084 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 758085 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 758085 INFO (zkConnectionManagerCallback-8741-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 758086 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 758088 WARN
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080]-SendThread(127.0.0.1:38225))
[ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for
session 0x1004ff2eba60002.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x1004ff2eba60002, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x1004ff2eba60002, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 758190 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 758190 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 758190 INFO (jetty-launcher-8742-thread-2) [ ]
o.e.j.s.Server jetty-9.4.53.v20231009; built: 2023-10-09T12:29:09.265Z; git:
27bde00a0b95a1d5bbee0eae7984f891d2d0f8c9; jvm 1.8.0_362-b09
[junit4] 2> 758190 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 758190 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 758190 INFO (jetty-launcher-8742-thread-1) [ ]
o.e.j.s.Server jetty-9.4.53.v20231009; built: 2023-10-09T12:29:09.265Z; git:
27bde00a0b95a1d5bbee0eae7984f891d2d0f8c9; jvm 1.8.0_362-b09
[junit4] 2> 758202 INFO (jetty-launcher-8742-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 758202 INFO (jetty-launcher-8742-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 758202 INFO (jetty-launcher-8742-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 758210 INFO (jetty-launcher-8742-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 758210 INFO (jetty-launcher-8742-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 758210 INFO (jetty-launcher-8742-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 758210 INFO (jetty-launcher-8742-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6887848{/solr,null,AVAILABLE}
[junit4] 2> 758211 INFO (jetty-launcher-8742-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@f5cb272{/solr,null,AVAILABLE}
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@3cc76951{SSL, (ssl,
http/1.1)}{127.0.0.1:42407}
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-1) [ ]
o.e.j.s.Server Started @758282ms
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@538b25d1{SSL, (ssl,
http/1.1)}{127.0.0.1:36405}
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
zkHost=127.0.0.1:38225/solr, hostPort=42407}
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-2) [ ]
o.e.j.s.Server Started @758282ms
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
zkHost=127.0.0.1:38225/solr, hostPort=36405}
[junit4] 2> 758212 ERROR (jetty-launcher-8742-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 758212 ERROR (jetty-launcher-8742-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.11.4
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.11.4
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2024-04-03T19:05:37.745Z
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr
[junit4] 2> 758212 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2024-04-03T19:05:37.745Z
[junit4] 2> 758216 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 758217 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 758218 INFO (zkConnectionManagerCallback-8744-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 758218 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 758219 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 758220 INFO (zkConnectionManagerCallback-8746-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 758220 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 758221 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 758224 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 758224 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 758225 WARN
(jetty-launcher-8742-thread-1-SendThread(127.0.0.1:38225)) [ ]
o.a.z.ClientCnxn An exception was thrown while closing send thread for session
0x1004ff2eba60003.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x1004ff2eba60003, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x1004ff2eba60003, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 758225 WARN
(jetty-launcher-8742-thread-2-SendThread(127.0.0.1:38225)) [ ]
o.a.z.ClientCnxn An exception was thrown while closing send thread for session
0x1004ff2eba60004.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x1004ff2eba60004, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x1004ff2eba60004, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 758802 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 758802 WARN (jetty-launcher-8742-thread-1) [ ]
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport
since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 758803 WARN (jetty-launcher-8742-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@70991194[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 758803 WARN (jetty-launcher-8742-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@70991194[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 758809 WARN (jetty-launcher-8742-thread-1) [ ]
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport
since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 758810 WARN (jetty-launcher-8742-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@4908cd93[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 758810 WARN (jetty-launcher-8742-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@4908cd93[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 758811 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38225/solr
[junit4] 2> 758812 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 758813 INFO (zkConnectionManagerCallback-8760-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 758813 INFO (jetty-launcher-8742-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 758899 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 758899 WARN (jetty-launcher-8742-thread-2) [ ]
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport
since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 758900 WARN (jetty-launcher-8742-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@58b854b1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 758900 WARN (jetty-launcher-8742-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@58b854b1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 758903 WARN (jetty-launcher-8742-thread-2) [ ]
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport
since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 758906 WARN (jetty-launcher-8742-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@73733146[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 758906 WARN (jetty-launcher-8742-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@73733146[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 758907 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38225/solr
[junit4] 2> 758908 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 758909 INFO (zkConnectionManagerCallback-8768-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 758909 INFO (jetty-launcher-8742-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 758915 INFO (jetty-launcher-8742-thread-1)
[n:127.0.0.1:42407_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 758918 INFO (zkConnectionManagerCallback-8770-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 758918 INFO (jetty-launcher-8742-thread-1)
[n:127.0.0.1:42407_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 759017 WARN (jetty-launcher-8742-thread-1)
[n:127.0.0.1:42407_solr ] o.a.s.c.ZkController Contents of zookeeper
/security.json are world-readable; consider setting up ACLs as described in
https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 759018 INFO (jetty-launcher-8742-thread-2)
[n:127.0.0.1:36405_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 759026 INFO (zkConnectionManagerCallback-8774-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 759026 INFO (jetty-launcher-8742-thread-2)
[n:127.0.0.1:36405_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 759026 INFO (jetty-launcher-8742-thread-1)
[n:127.0.0.1:42407_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:42407_solr
[junit4] 2> 759027 INFO (jetty-launcher-8742-thread-1)
[n:127.0.0.1:42407_solr ] o.a.s.c.Overseer Overseer
(id=72145498792132615-127.0.0.1:42407_solr-n_0000000000) starting
[junit4] 2> 759041 INFO
(OverseerStateUpdate-72145498792132615-127.0.0.1:42407_solr-n_0000000000)
[n:127.0.0.1:42407_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:42407_solr
[junit4] 2> 759042 WARN (jetty-launcher-8742-thread-2)
[n:127.0.0.1:36405_solr ] o.a.s.c.ZkController Contents of zookeeper
/security.json are world-readable; consider setting up ACLs as described in
https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 759050 INFO (jetty-launcher-8742-thread-1)
[n:127.0.0.1:42407_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42407_solr
[junit4] 2> 759057 INFO (zkCallback-8769-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 759061 INFO (jetty-launcher-8742-thread-1)
[n:127.0.0.1:42407_solr ] o.a.s.c.ZkController non-data nodes now []
[junit4] 2> 759066 INFO (zkCallback-8773-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 759067 INFO (jetty-launcher-8742-thread-1)
[n:127.0.0.1:42407_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 759067 WARN (jetty-launcher-8742-thread-1)
[n:127.0.0.1:42407_solr ] 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> 759074 INFO (jetty-launcher-8742-thread-2)
[n:127.0.0.1:36405_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:36405_solr as DOWN
[junit4] 2> 759075 INFO (jetty-launcher-8742-thread-2)
[n:127.0.0.1:36405_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient core cache for max 2147483647 cores with initial capacity of 1024
[junit4] 2> 759075 INFO (jetty-launcher-8742-thread-2)
[n:127.0.0.1:36405_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36405_solr
[junit4] 2> 759079 INFO (zkCallback-8769-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 759080 INFO (zkCallback-8773-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 759082 INFO (jetty-launcher-8742-thread-2)
[n:127.0.0.1:36405_solr ] o.a.s.c.ZkController non-data nodes now []
[junit4] 2> 759085 INFO (jetty-launcher-8742-thread-2)
[n:127.0.0.1:36405_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 759086 WARN (jetty-launcher-8742-thread-2)
[n:127.0.0.1:36405_solr ] 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> 759108 INFO (jetty-launcher-8742-thread-1)
[n:127.0.0.1:42407_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_3BF5A92D2F4D9080-001/tempDir-001/node1
[junit4] 2> 759116 INFO (jetty-launcher-8742-thread-2)
[n:127.0.0.1:36405_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_3BF5A92D2F4D9080-001/tempDir-001/node2
[junit4] 2> 759130 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 759130 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 759131 INFO (zkConnectionManagerCallback-8793-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 759131 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 759133 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 759134 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:38225/solr ready
[junit4] 2> 759166 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=CLUSTERPROP&name=legacyCloud&val=false&wt=javabin&version=2)
[junit4] 2> 759168 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 759169 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=CREATE&async=e032fdbf-ce3a-4d13-8042-c41a4971a045&name=testAsyncOperations&collection.configName=conf1&numShards=1&router.name=implicit&shards=shard1&nrtReplicas=1&wt=javabin&version=2)
[junit4] 2> 759172 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=e032fdbf-ce3a-4d13-8042-c41a4971a045&shards=shard1&collection.configName=conf1&name=testAsyncOperations&router.name=implicit&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=3
[junit4] 2> 759173 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=REQUESTSTATUS&requestid=e032fdbf-ce3a-4d13-8042-c41a4971a045&wt=javabin&version=2)
[junit4] 2> 759175 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=e032fdbf-ce3a-4d13-8042-c41a4971a045&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 759176 INFO
(OverseerThreadFactory-8781-thread-1-processing-n:127.0.0.1:42407_solr)
[n:127.0.0.1:42407_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
testAsyncOperations
[junit4] 2> 759287 INFO
(OverseerStateUpdate-72145498792132615-127.0.0.1:42407_solr-n_0000000000)
[n:127.0.0.1:42407_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testAsyncOperations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testAsyncOperations_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:36405_solr",
[junit4] 2> "base_url":"https://127.0.0.1:36405/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 759505 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/cores?null)
[junit4] 2> 759505 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=testAsyncOperations&version=2&replicaType=NRT&async=e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929&coreNodeName=core_node2&name=testAsyncOperations_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin}
status=0 QTime=0
[junit4] 2> 759505 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=testAsyncOperations&version=2&replicaType=NRT&async=e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929&coreNodeName=core_node2&name=testAsyncOperations_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin
[junit4] 2> 759520 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 8.11.4
[junit4] 2> 759522 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema
name=minimal
[junit4] 2> 759522 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 759522 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating
SolrCore 'testAsyncOperations_shard1_replica_n1' using configuration from
configset conf1, trusted=true
[junit4] 2> 759523 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.SolrCore
[[testAsyncOperations_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_3BF5A92D2F4D9080-001/tempDir-001/node2/testAsyncOperations_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_3BF5A92D2F4D9080-001/tempDir-001/node2/testAsyncOperations_shard1_replica_n1/data/]
[junit4] 2> 759537 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/cores?null)
[junit4] 2> 759537 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929
[junit4] 2> 759537 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 759560 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 759560 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 759561 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 759561 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 759562 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 759563 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded
null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 759563 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000 ms
[junit4] 2> 759563 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1795341564348727296
[junit4] 2> 759565 INFO
(searcherExecutor-8795-thread-1-processing-x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 r:core_node2 CREATE
n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1) [n:127.0.0.1:36405_solr
c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.SolrCore
[testAsyncOperations_shard1_replica_n1] Registered new searcher autowarm time:
0 ms
[junit4] 2> 759568 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful
update of terms at /collections/testAsyncOperations/terms/shard1 to
Terms{values={core_node2=0}, version=0}
[junit4] 2> 759568 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created
/collections/testAsyncOperations/leaders/shard1
[junit4] 2> 759572 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 759572 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I
may be the new leader - try and sync
[junit4] 2> 759572 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:36405/solr/testAsyncOperations_shard1_replica_n1/
[junit4] 2> 759572 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 759572 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.SyncStrategy
https://127.0.0.1:36405/solr/testAsyncOperations_shard1_replica_n1/ has no
replicas
[junit4] 2> 759572 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/testAsyncOperations/leaders/shard1/leader after winning as
/collections/testAsyncOperations/leader_elect/shard1/election/72145498792132616-core_node2-n_0000000000
[junit4] 2> 759575 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I
am the new leader:
https://127.0.0.1:36405/solr/testAsyncOperations_shard1_replica_n1/ shard1
[junit4] 2> 759677 INFO (zkCallback-8773-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 759678 INFO
(parallelCoreAdminExecutor-8786-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929 CREATE)
[n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader,
no recovery necessary
[junit4] 2> 759780 INFO (zkCallback-8773-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 759781 INFO (zkCallback-8773-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 760176 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=REQUESTSTATUS&requestid=e032fdbf-ce3a-4d13-8042-c41a4971a045&wt=javabin&version=2)
[junit4] 2> 760177 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=e032fdbf-ce3a-4d13-8042-c41a4971a045&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 760544 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/cores?null)
[junit4] 2> 760544 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929
[junit4] 2> 760544 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=e032fdbf-ce3a-4d13-8042-c41a4971a0451341321417094929&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 760551 INFO
(OverseerThreadFactory-8781-thread-1-processing-n:127.0.0.1:42407_solr)
[n:127.0.0.1:42407_solr ] 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> 761177 INFO
(OverseerCollectionConfigSetProcessor-72145498792132615-127.0.0.1:42407_solr-n_0000000000)
[n:127.0.0.1:42407_solr ] 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> 761178 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=REQUESTSTATUS&requestid=e032fdbf-ce3a-4d13-8042-c41a4971a045&wt=javabin&version=2)
[junit4] 2> 761179 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=e032fdbf-ce3a-4d13-8042-c41a4971a045&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 761180 INFO (qtp1500728993-14316) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=DELETESTATUS&requestid=e032fdbf-ce3a-4d13-8042-c41a4971a045&wt=javabin&version=2)
[junit4] 2> 761182 INFO (qtp1500728993-14316) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=e032fdbf-ce3a-4d13-8042-c41a4971a045&action=DELETESTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 761182 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncRequests-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection:
testAsyncOperations
[junit4] 2> 761184 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/testAsyncOperations/update?wt=javabin&version=2)
[junit4] 2> 761187 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful
update of terms at /collections/testAsyncOperations/terms/shard1 to
Terms{values={core_node2=1}, version=1}
[junit4] 2> 761188 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory
[testAsyncOperations_shard1_replica_n1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[0 (1795341566049517568), 1
(1795341566050566144), 2 (1795341566050566145), 3 (1795341566050566146), 4
(1795341566050566147), 5 (1795341566050566148), 6 (1795341566051614720), 7
(1795341566051614721), 8 (1795341566051614722), 9 (1795341566051614723), ...
(24 adds)]} 0 4
[junit4] 2> 761188 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/testAsyncOperations/update?null)
[junit4] 2> 761192 INFO
(searcherExecutor-8795-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1 c:testAsyncOperations s:shard1
r:core_node2) [n:127.0.0.1:36405_solr c:testAsyncOperations s:shard1
r:core_node2 x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.SolrCore
[testAsyncOperations_shard1_replica_n1] Registered new searcher autowarm time:
0 ms
[junit4] 2> 761192 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory
[testAsyncOperations_shard1_replica_n1] webapp=/solr path=/update
params={_stateVer_=testAsyncOperations:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 4
[junit4] 2> 761193 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/testAsyncOperations/select?q=*%3A*&shards=shard1&_stateVer_=testAsyncOperations%3A3&wt=javabin&version=2)
[junit4] 2> 761193 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.S.Request
[testAsyncOperations_shard1_replica_n1] webapp=/solr path=/select
params={q=*:*&shards=shard1&_stateVer_=testAsyncOperations:3&wt=javabin&version=2}
hits=24 status=0 QTime=0
[junit4] 2> 761194 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=RELOAD&async=baa5d94b-715e-4dc5-b662-93234a5a8d2b&name=testAsyncOperations&wt=javabin&version=2)
[junit4] 2> 761197 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=baa5d94b-715e-4dc5-b662-93234a5a8d2b&name=testAsyncOperations&action=RELOAD&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 761197 INFO (qtp1500728993-14316) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=REQUESTSTATUS&requestid=baa5d94b-715e-4dc5-b662-93234a5a8d2b&wt=javabin&version=2)
[junit4] 2> 761199 INFO (qtp1500728993-14316) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=baa5d94b-715e-4dc5-b662-93234a5a8d2b&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 761199 INFO
(OverseerThreadFactory-8781-thread-2-processing-n:127.0.0.1:42407_solr)
[n:127.0.0.1:42407_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Executing Collection Cmd=action=RELOAD,
asyncId=baa5d94b-715e-4dc5-b662-93234a5a8d2b
[junit4] 2> 761201 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/cores?null)
[junit4] 2> 761202 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={core=testAsyncOperations_shard1_replica_n1&async=baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670&qt=/admin/cores&action=RELOAD&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 761202 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/cores?null)
[junit4] 2> 761203 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670
[junit4] 2> 761203 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 761207 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.4
[junit4] 2> 761207 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 761208 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 761208 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.c.CoreContainer Reloading SolrCore
'testAsyncOperations_shard1_replica_n1' using configuration from configset conf1
[junit4] 2> 761208 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.c.SolrCore [[testAsyncOperations_shard1_replica_n1] ] Opening new
SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_3BF5A92D2F4D9080-001/tempDir-001/node2/testAsyncOperations_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_3BF5A92D2F4D9080-001/tempDir-001/node2/testAsyncOperations_shard1_replica_n1/data/]
[junit4] 2> 761231 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 761231 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 761233 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 761233 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 761233 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 761235 INFO
(searcherExecutor-8801-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.c.SolrCore [testAsyncOperations_shard1_replica_n1] Registered new
searcher autowarm time: 0 ms
[junit4] 2> 761236 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 761237 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr ] o.a.s.c.SolrCore
[testAsyncOperations_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@478a2bea
[junit4] 2> 761237 INFO
(searcherExecutor-8801-thread-1-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr x:testAsyncOperations_shard1_replica_n1 ]
o.a.s.c.SolrCore [testAsyncOperations_shard1_replica_n1] Registered new
searcher autowarm time: 0 ms
[junit4] 2> 761237 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.testAsyncOperations.shard1.replica_n1
tag=SolrCore@478a2bea
[junit4] 2> 761237 INFO
(parallelCoreAdminExecutor-8786-thread-2-processing-n:127.0.0.1:36405_solr
x:testAsyncOperations_shard1_replica_n1
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670 RELOAD)
[n:127.0.0.1:36405_solr ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.testAsyncOperations.shard1.leader
tag=SolrCore@478a2bea
[junit4] 2> 762200 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=REQUESTSTATUS&requestid=baa5d94b-715e-4dc5-b662-93234a5a8d2b&wt=javabin&version=2)
[junit4] 2> 762202 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=baa5d94b-715e-4dc5-b662-93234a5a8d2b&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 762204 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/cores?null)
[junit4] 2> 762204 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670
[junit4] 2> 762204 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=baa5d94b-715e-4dc5-b662-93234a5a8d2b1341323330863670&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 762207 INFO
(OverseerThreadFactory-8781-thread-2-processing-n:127.0.0.1:42407_solr)
[n:127.0.0.1:42407_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000001 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 763200 INFO
(OverseerCollectionConfigSetProcessor-72145498792132615-127.0.0.1:42407_solr-n_0000000000)
[n:127.0.0.1:42407_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000001 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 763203 INFO (qtp1500728993-14316) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=REQUESTSTATUS&requestid=baa5d94b-715e-4dc5-b662-93234a5a8d2b&wt=javabin&version=2)
[junit4] 2> 763203 INFO (qtp1500728993-14316) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=baa5d94b-715e-4dc5-b662-93234a5a8d2b&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 763204 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=DELETESTATUS&requestid=baa5d94b-715e-4dc5-b662-93234a5a8d2b&wt=javabin&version=2)
[junit4] 2> 763205 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=baa5d94b-715e-4dc5-b662-93234a5a8d2b&action=DELETESTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 763208 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=CREATESHARD&async=cae500c8-b35a-4657-b13d-91fcce451bb7&collection=testAsyncOperations&shard=shard2&wt=javabin&version=2)
[junit4] 2> 763211 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
c:testAsyncOperations ] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/collections
params={async=cae500c8-b35a-4657-b13d-91fcce451bb7&action=CREATESHARD&collection=testAsyncOperations&shard=shard2&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 763211 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=REQUESTSTATUS&requestid=cae500c8-b35a-4657-b13d-91fcce451bb7&wt=javabin&version=2)
[junit4] 2> 763213 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=cae500c8-b35a-4657-b13d-91fcce451bb7&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 763213 INFO
(OverseerThreadFactory-8781-thread-3-processing-n:127.0.0.1:42407_solr)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 ]
o.a.s.c.a.c.CreateShardCmd Create shard invoked: {
[junit4] 2> "collection":"testAsyncOperations",
[junit4] 2> "shard":"shard2",
[junit4] 2> "async":"cae500c8-b35a-4657-b13d-91fcce451bb7",
[junit4] 2> "operation":"createshard"}
[junit4] 2> 763216 INFO (zkCallback-8773-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 763216 INFO (zkCallback-8773-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 764214 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=REQUESTSTATUS&requestid=cae500c8-b35a-4657-b13d-91fcce451bb7&wt=javabin&version=2)
[junit4] 2> 764215 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=cae500c8-b35a-4657-b13d-91fcce451bb7&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 764221 INFO
(OverseerThreadFactory-8781-thread-3-processing-n:127.0.0.1:42407_solr)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 ]
o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:42407_solr for creating new
replica of shard shard2 for collection testAsyncOperations
[junit4] 2> 764223 INFO
(OverseerThreadFactory-8781-thread-3-processing-n:127.0.0.1:42407_solr)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 ]
o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 764224 INFO
(OverseerStateUpdate-72145498792132615-127.0.0.1:42407_solr-n_0000000000)
[n:127.0.0.1:42407_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"testAsyncOperations",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testAsyncOperations_shard2_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:42407_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 764326 INFO (zkCallback-8773-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 764327 INFO (zkCallback-8773-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 764472 INFO (qtp910098977-14314) [n:127.0.0.1:42407_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:42407/solr/admin/cores?null)
[junit4] 2> 764473 INFO (qtp910098977-14314) [n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={async=cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211&qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&name=testAsyncOperations_shard2_replica_n3&action=CREATE&collection=testAsyncOperations&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=0
[junit4] 2> 764473 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr x:testAsyncOperations_shard2_replica_n3 ]
o.a.s.h.a.CoreAdminOperation core create command
async=cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211&qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&name=testAsyncOperations_shard2_replica_n3&action=CREATE&collection=testAsyncOperations&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 764474 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr x:testAsyncOperations_shard2_replica_n3 ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max
2147483647 cores with initial capacity of 1024
[junit4] 2> 764478 INFO (qtp910098977-14315) [n:127.0.0.1:42407_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:42407/solr/admin/cores?null)
[junit4] 2> 764479 INFO (qtp910098977-14315) [n:127.0.0.1:42407_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211
[junit4] 2> 764479 INFO (qtp910098977-14315) [n:127.0.0.1:42407_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 764489 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 8.11.4
[junit4] 2> 764491 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.s.IndexSchema Schema
name=minimal
[junit4] 2> 764491 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 764491 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.CoreContainer Creating
SolrCore 'testAsyncOperations_shard2_replica_n3' using configuration from
configset conf1, trusted=true
[junit4] 2> 764492 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.SolrCore
[[testAsyncOperations_shard2_replica_n3] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_3BF5A92D2F4D9080-001/tempDir-001/node1/testAsyncOperations_shard2_replica_n3],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_3BF5A92D2F4D9080-001/tempDir-001/node1/testAsyncOperations_shard2_replica_n3/data/]
[junit4] 2> 764538 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 764538 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 764538 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 764539 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 764544 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 764544 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded
null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 764544 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000 ms
[junit4] 2> 764545 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1795341569572732928
[junit4] 2> 764548 INFO
(searcherExecutor-8806-thread-1-processing-x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 r:core_node4 CREATE
n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2) [n:127.0.0.1:42407_solr
c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.SolrCore
[testAsyncOperations_shard2_replica_n3] Registered new searcher autowarm time:
0 ms
[junit4] 2> 764552 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.ZkShardTerms Successful
update of terms at /collections/testAsyncOperations/terms/shard2 to
Terms{values={core_node4=0}, version=0}
[junit4] 2> 764553 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ]
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created
/collections/testAsyncOperations/leaders/shard2
[junit4] 2> 764560 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 764560 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I
may be the new leader - try and sync
[junit4] 2> 764560 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:42407/solr/testAsyncOperations_shard2_replica_n3/
[junit4] 2> 764560 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.SyncStrategy Sync Success -
now sync replicas to me
[junit4] 2> 764561 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.SyncStrategy
https://127.0.0.1:42407/solr/testAsyncOperations_shard2_replica_n3/ has no
replicas
[junit4] 2> 764561 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/testAsyncOperations/leaders/shard2/leader after winning as
/collections/testAsyncOperations/leader_elect/shard2/election/72145498792132615-core_node4-n_0000000000
[junit4] 2> 764566 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I
am the new leader:
https://127.0.0.1:42407/solr/testAsyncOperations_shard2_replica_n3/ shard2
[junit4] 2> 764669 INFO (zkCallback-8773-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 764669 INFO (zkCallback-8769-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 764669 INFO (zkCallback-8773-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 764670 INFO
(parallelCoreAdminExecutor-8783-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211 CREATE)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.ZkController I am the leader,
no recovery necessary
[junit4] 2> 764774 INFO (zkCallback-8769-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 764774 INFO (zkCallback-8769-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 764774 INFO (zkCallback-8773-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 764774 INFO (zkCallback-8773-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testAsyncOperations/state.json] for collection
[testAsyncOperations] has occurred - updating... (live nodes size: [2])
[junit4] 2> 765216 INFO (qtp1500728993-14316) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=REQUESTSTATUS&requestid=cae500c8-b35a-4657-b13d-91fcce451bb7&wt=javabin&version=2)
[junit4] 2> 765217 INFO (qtp1500728993-14316) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=cae500c8-b35a-4657-b13d-91fcce451bb7&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 765482 INFO (qtp910098977-14311) [n:127.0.0.1:42407_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:42407/solr/admin/cores?null)
[junit4] 2> 765483 INFO (qtp910098977-14311) [n:127.0.0.1:42407_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211
[junit4] 2> 765483 INFO (qtp910098977-14311) [n:127.0.0.1:42407_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=cae500c8-b35a-4657-b13d-91fcce451bb71341326560642211&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 765484 INFO
(OverseerThreadFactory-8781-thread-3-processing-n:127.0.0.1:42407_solr)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 ]
o.a.s.c.a.c.CreateShardCmd Finished create command on all shards for
collection: testAsyncOperations
[junit4] 2> 765486 INFO
(OverseerThreadFactory-8781-thread-3-processing-n:127.0.0.1:42407_solr)
[n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2 ]
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> 766218 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=REQUESTSTATUS&requestid=cae500c8-b35a-4657-b13d-91fcce451bb7&wt=javabin&version=2)
[junit4] 2> 766219 INFO (qtp1500728993-14318) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=cae500c8-b35a-4657-b13d-91fcce451bb7&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 766220 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/admin/collections?action=DELETESTATUS&requestid=cae500c8-b35a-4657-b13d-91fcce451bb7&wt=javabin&version=2)
[junit4] 2> 766221 INFO (qtp1500728993-14320) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=cae500c8-b35a-4657-b13d-91fcce451bb7&action=DELETESTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 766222 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/testAsyncOperations/update?wt=javabin&version=2)
[junit4] 2> 766253 INFO (qtp910098977-14315) [n:127.0.0.1:42407_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:42407/solr/testAsyncOperations_shard2_replica_n3/update?update.distrib=TOLEADER&distrib.from=https%3A%2F%2F127.0.0.1%3A36405%2Fsolr%2FtestAsyncOperations_shard1_replica_n1%2F&wt=javabin&version=2)
[junit4] 2> 766256 INFO (qtp910098977-14315) [n:127.0.0.1:42407_solr
c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.ZkShardTerms Successful
update of terms at /collections/testAsyncOperations/terms/shard2 to
Terms{values={core_node4=1}, version=1}
[junit4] 2> 766256 INFO (qtp910098977-14315) [n:127.0.0.1:42407_solr
c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory
[testAsyncOperations_shard2_replica_n3] webapp=/solr path=/update
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:36405/solr/testAsyncOperations_shard1_replica_n1/&wt=javabin&version=2}{add=[25
(1795341571364749312)]} 0 3
[junit4] 2> 766257 INFO (qtp1500728993-14306) [n:127.0.0.1:36405_solr
c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory
[testAsyncOperations_shard1_replica_n1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[25]} 0 34
[junit4] 2> 766257 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/testAsyncOperations/update?null)
[junit4] 2> 766258 INFO (qtp910098977-14311) [n:127.0.0.1:42407_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:42407/solr/testAsyncOperations_shard2_replica_n3/update?update.distrib=TOLEADER&distrib.from=https%3A%2F%2F127.0.0.1%3A36405%2Fsolr%2FtestAsyncOperations_shard1_replica_n1%2F)
[junit4] 2> 766263 INFO
(searcherExecutor-8806-thread-1-processing-n:127.0.0.1:42407_solr
x:testAsyncOperations_shard2_replica_n3 c:testAsyncOperations s:shard2
r:core_node4) [n:127.0.0.1:42407_solr c:testAsyncOperations s:shard2
r:core_node4 x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.SolrCore
[testAsyncOperations_shard2_replica_n3] Registered new searcher autowarm time:
0 ms
[junit4] 2> 766263 INFO (qtp910098977-14311) [n:127.0.0.1:42407_solr
c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory
[testAsyncOperations_shard2_replica_n3] webapp=/solr path=/update
params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:36405/solr/testAsyncOperations_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 4
[junit4] 2> 766264 INFO (qtp1500728993-14317) [n:127.0.0.1:36405_solr
c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory
[testAsyncOperations_shard1_replica_n1] webapp=/solr path=/update
params={_stateVer_=testAsyncOperations:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 6
[junit4] 2> 766265 INFO (qtp1500728993-14316) [n:127.0.0.1:36405_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:36405/solr/testAsyncOperations/select?q=*%3A*&shards=shard2&_stateVer_=testAsyncOperations%3A7&wt=javabin&version=2)
[junit4] 2> 766308 INFO (qtp910098977-14315) [n:127.0.0.1:42407_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:42407/solr/testAsyncOperations_shard2_replica_n3/select?null)
[junit4] 2> 766309 INFO (qtp910098977-14315) [n:127.0.0.1:42407_solr
c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.S.Request
[testAsyncOperations_shard2_replica_n3] webapp=/solr path=/select
params={df=text&distrib=false&_stateVer_=testAsyncOperations:7&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=https://127.0.0.1:42407/solr/testAsyncOperations_shard2_replica_n3/&rid=127.0.0.1-143719&rows=10&version=2&q=*:*&omitHeader=false&NOW=1712171145798&isShard=true&wt=javabin}
hits=1 status=0 QTime=0
[junit4] 2> 766311 INFO (qtp910098977-14311) [n:127.0.0.1:42407_solr
] o.a.s.s.HttpSolrCall
HttpSolrCall.init(https://127.0.0.1:42407/solr/testAsyncOperations_shard2_replica_n3/select?null)
[junit4] 2> 766311 INFO (qtp910098977-14311) [n:127.0.0.1:42407_solr
c:testAsyncOperations s:shard2 r:core_node4
x:testAsyncOperations_shard2_replica_n3 ] o.a.s.c.S.Request
[testAsyncOperations_shard2_replica_n3] webapp=/solr path=/select
params={df=text&distrib=false&_stateVer_=testAsyncOperations:7&shards.purpose=64&shard.url=https://127.0.0.1:42407/solr/testAsyncOperations_shard2_replica_n3/&rid=127.0.0.1-143719&version=2&q=*:*&omitHeader=false&NOW=1712171145798&ids=25&isShard=true&wt=javabin}
status=0 QTime=0
[junit4] 2> 766312 INFO (qtp1500728993-14316) [n:127.0.0.1:36405_solr
c:testAsyncOperations s:shard1 r:core_node2
x:testAsyncOperations_shard1_replica_n1 ] o.a.s.c.S.Request
[testAsyncOperations_shard1_replica_n1] webapp=/so
[...truncated too long message...]
icManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 911683 INFO (jetty-closer-9073-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster
tag=null
[junit4] 2> 911683 INFO (jetty-closer-9073-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster
tag=null
[junit4] 2> 911685 INFO (closeThreadPool-9085-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=72145507906879494-127.0.0.1:39763_solr-n_0000000000) closing
[junit4] 2> 911690 INFO
(OverseerStateUpdate-72145507906879494-127.0.0.1:39763_solr-n_0000000000)
[n:127.0.0.1:39763_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:39763_solr
[junit4] 2> 911691 INFO
(OverseerAutoScalingTriggerThread-72145507906879494-127.0.0.1:39763_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 911694 INFO (closeThreadPool-9085-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72145507906879494-127.0.0.1:39763_solr-n_0000000000) closing
[junit4] 2> 911794 INFO (jetty-closer-9073-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@f8437fa{SSL, (ssl,
http/1.1)}{127.0.0.1:0}
[junit4] 2> 911794 INFO (jetty-closer-9073-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@36cb2601{/solr,null,STOPPED}
[junit4] 2> 911794 INFO (jetty-closer-9073-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 911893 INFO (jetty-closer-9073-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72145507906879494-127.0.0.1:39763_solr-n_0000000000) closing
[junit4] 2> 911894 INFO (jetty-closer-9073-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@417fae92{SSL, (ssl,
http/1.1)}{127.0.0.1:0}
[junit4] 2> 911895 INFO (jetty-closer-9073-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@94fa6aa{/solr,null,STOPPED}
[junit4] 2> 911895 INFO (jetty-closer-9073-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 911895 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 911896 WARN
(TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[3BF5A92D2F4D9080]-SendThread(127.0.0.1:44495))
[ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for
session 0x1004ff50aee0000.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x1004ff50aee0000, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x1004ff50aee0000, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 911998 WARN
(TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[3BF5A92D2F4D9080]-SendThread(127.0.0.1:44495))
[ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for
session 0x1004ff50aee0001.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x1004ff50aee0001, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x1004ff50aee0001, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 912100 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> 10
/solr/collections/testasynccollectioncreation/terms/shard1_1
[junit4] 2> 6
/solr/collections/testasynccollectioncreation/terms/shard1_0
[junit4] 2> 5
/solr/collections/testasynccollectioncreation/terms/shard1
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2
/solr/collections/testasynccollectioncreation/collectionprops.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 56
/solr/collections/testasynccollectioncreation/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 51 /solr/overseer/queue
[junit4] 2> 9 /solr/overseer/collection-queue-work
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 3
/solr/collections/testasynccollectioncreation/state.json
[junit4] 2>
[junit4] 2> 912105 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:44495
[junit4] 2> 912105 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:44495
[junit4] 2> 912105 INFO
(TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[3BF5A92D2F4D9080])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 44495
[junit4] 2> 912107 INFO
(SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[3BF5A92D2F4D9080]-worker) [
] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-255 after mutting 0 log
messages
[junit4] 2> 912120 INFO
(SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[3BF5A92D2F4D9080]-worker) [
] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-256 for ERROR logs
matching regex: ignore_exception
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_3BF5A92D2F4D9080-001
[junit4] 2> Apr 03, 2024 7:08:11 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87):
{_root_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)),
id=PostingsFormat(name=MockRandom),
_route_=PostingsFormat(name=LuceneVarGapDocFreqInterval)},
docValues:{_version_=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1812,
maxMBSortInHeap=7.78744497278319,
sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=lt,
timezone=America/Dawson_Creek
[junit4] 2> NOTE: Linux 5.4.0-174-generic amd64/Temurin 1.8.0_362
(64-bit)/cpus=4,threads=1,free=282885048,total=518520832
[junit4] 2> NOTE: All tests run in this JVM: [StressHdfsTest,
DistributedQueryComponentOptimizationTest, TestLegacyField, TestRTGBase,
HttpSolrCallCloudTest, TestSolrCloudWithHadoopAuthPlugin,
ClusterStateUpdateTest, TestFiltering, HttpPartitionTest,
DistribJoinFromCollectionTest, MoreLikeThisComponentTest,
DistributedFacetExistsSmallTest, TestSolrCoreParser, DateFieldTest,
TaggingAttributeTest, AsyncCallRequestStatusResponseTest, ExplicitHLLTest,
V2ApiIntegrationTest, TestJsonFacetsWithNestedObjects, TestReplicaProperties,
TestWordDelimiterFilterFactory, TestLazyCores, TestPayloadScoreQParserPlugin,
TestRealTimeGet, SolrInfoBeanTest, DistributedTermsComponentTest,
ZookeeperStatusHandlerTest, TestTestInjection, TestBinaryField,
UpdateRequestProcessorFactoryTest, TestFunctionQuery, FuzzySearchTest,
ResponseBuilderTest, TestCursorMarkWithoutUniqueKey, RegexBoostProcessorTest,
CustomTermsComponentTest, DistanceFunctionTest,
TestTolerantUpdateProcessorRandomCloud, TestCollapseQParserPlugin,
TestPackages, TestDocumentBuilder, DocExpirationUpdateProcessorFactoryTest,
SolrGraphiteReporterTest, CdcrVersionReplicationTest, CoreAdminOperationTest,
NumberUtilsTest, SpellingQueryConverterTest, TestStressLiveNodes,
TestSchemaVersionResource, TolerantUpdateProcessorTest,
WrapperMergePolicyFactoryTest, TestSolrIndexConfig, FileTypeMagicUtilTest,
TestSimpleTextCodec, TestXmlQParser, TestCloudInspectUtil,
TestInPlaceUpdatesRequiredField, SpellCheckComponentTest,
SearchRateTriggerTest, TermVectorComponentDistributedTest, PurgeGraphTest,
TestCharFilters, TestLegacyFieldCache, TestStressReorder, RemoteQueryErrorTest,
TestRawResponseWriter, TestUnifiedSolrHighlighter, TestCollationField,
TestManagedSchemaAPI, NodeLostTriggerIntegrationTest, TestSQLHandlerNonCloud,
LeaderVoteWaitTimeoutTest, SolrIndexConfigTest, TestDownShardTolerantSearch,
OverseerModifyCollectionTest, TestRandomDVFaceting, TestExtendedDismaxParser,
TestBM25SimilarityFactory, CategoryRoutedAliasUpdateProcessorTest,
TestSolrCloudWithKerberosAlt, AddBlockUpdateTest, TestMultiWordSynonyms,
IndexBasedSpellCheckerTest, TestCloudNestedDocsSort, SolrLogPostToolTest,
CollectionsAPIAsyncDistributedZkTest]
[junit4] Completed [312/959 (1!)] on J2 in 154.99s, 4 tests, 1 failure <<<
FAILURES!
[...truncated 56418 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
