You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@solr.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2024/02/21 00:29:48 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.11 - Build # 562 - Still Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.11/562/
2 tests failed.
FAILED: org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitMixedReplicaTypes
Error Message:
Timeout occurred while waiting response from server at: http://127.0.0.1:43135/_n/h
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:43135/_n/h
at __randomizedtesting.SeedInfo.seed([9510D528F8A25D6A:2DD381880479881F]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:692)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:369)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:297)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1188)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:225)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:242)
at org.apache.solr.cloud.api.collections.ShardSplitTest.doSplitMixedReplicaTypes(ShardSplitTest.java:365)
at org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitMixedReplicaTypes(ShardSplitTest.java:350)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1084)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1055)
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)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:571)
... 57 more
FAILED: junit.framework.TestSuite.org.apache.solr.update.TestHdfsUpdateLog
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.update.TestHdfsUpdateLog: 1) Thread[id=1956, name=Command processor, state=WAITING, group=TGRP-TestHdfsUpdateLog] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.processQueue(BPServiceActor.java:1291) at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.run(BPServiceActor.java:1275)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.update.TestHdfsUpdateLog:
1) Thread[id=1956, name=Command processor, state=WAITING, group=TGRP-TestHdfsUpdateLog]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.processQueue(BPServiceActor.java:1291)
at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.run(BPServiceActor.java:1275)
at __randomizedtesting.SeedInfo.seed([9510D528F8A25D6A]:0)
Build Log:
[...truncated 13676 lines...]
[junit4] Suite: org.apache.solr.update.TestHdfsUpdateLog
[junit4] 2> 169408 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-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> 169408 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-48 after mutting 0 log messages
[junit4] 2> 169408 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-49 for ERROR logs matching regex: ignore_exception
[junit4] 2> 169409 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/solr.update.TestHdfsUpdateLog_9510D528F8A25D6A-001/data-dir-28-001
[junit4] 2> 169409 WARN (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=16 numCloses=16
[junit4] 2> 169409 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 169411 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-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> 169412 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 172565 WARN (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.a.h.s.a.s.AuthenticationFilter Unable to initialize FileSignerSecretProvider, falling back to use random secrets. Reason: access denied ("java.io.FilePermission" "/home/jenkins/hadoop-http-auth-signature-secret" "read")
[junit4] 2> 172578 WARN (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 172630 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] 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> 172656 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 172656 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 172656 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 172670 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3ff296{static,/static,jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.4-tests.jar!/webapps/static,AVAILABLE}
[junit4] 2> 172978 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.w.WebAppContext@5416efc8{hdfs,/,file:///home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/jetty-localhost-34923-hadoop-hdfs-3_2_4-tests_jar-_-any-4135586566376717625/webapp/,AVAILABLE}{jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.4-tests.jar!/webapps/hdfs}
[junit4] 2> 172979 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@48ec5f16{HTTP/1.1, (http/1.1)}{localhost:34923}
[junit4] 2> 172979 INFO (SUITE-TestHdfsUpdateLog-seed#[9510D528F8A25D6A]-worker) [ ] o.e.j.s.Server Started @173006ms
[junit4] 2> 175706 WARN (StorageLocationChecker thread 0) [ ] o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
[junit4] 2> 175843 WARN (Listener at localhost/39723) [ ] o.a.h.s.a.s.AuthenticationFilter Unable to initialize FileSignerSecretProvider, falling back to use random secrets. Reason: access denied ("java.io.FilePermission" "/home/jenkins/hadoop-http-auth-signature-secret" "read")
[junit4] 2> 175843 WARN (Listener at localhost/39723) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 175851 INFO (Listener at localhost/39723) [ ] 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> 175853 INFO (Listener at localhost/39723) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 175853 INFO (Listener at localhost/39723) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 175853 INFO (Listener at localhost/39723) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 175854 INFO (Listener at localhost/39723) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@273cdb3f{static,/static,jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.4-tests.jar!/webapps/static,AVAILABLE}
[junit4] 2> 176023 INFO (Listener at localhost/39723) [ ] o.e.j.s.h.ContextHandler Started o.e.j.w.WebAppContext@162743da{datanode,/,file:///home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/jetty-localhost-46249-hadoop-hdfs-3_2_4-tests_jar-_-any-8708728508434356933/webapp/,AVAILABLE}{jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.4-tests.jar!/webapps/datanode}
[junit4] 2> 176024 INFO (Listener at localhost/39723) [ ] o.e.j.s.AbstractConnector Started ServerConnector@744968f{HTTP/1.1, (http/1.1)}{localhost:46249}
[junit4] 2> 176024 INFO (Listener at localhost/39723) [ ] o.e.j.s.Server Started @176051ms
[junit4] 2> 178206 WARN (Listener at localhost/40225) [ ] o.a.h.s.a.s.AuthenticationFilter Unable to initialize FileSignerSecretProvider, falling back to use random secrets. Reason: access denied ("java.io.FilePermission" "/home/jenkins/hadoop-http-auth-signature-secret" "read")
[junit4] 2> 178208 WARN (Listener at localhost/40225) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 178212 INFO (Listener at localhost/40225) [ ] 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> 178215 INFO (Listener at localhost/40225) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 178215 INFO (Listener at localhost/40225) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 178215 INFO (Listener at localhost/40225) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 178215 INFO (Listener at localhost/40225) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6b336330{static,/static,jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.4-tests.jar!/webapps/static,AVAILABLE}
[junit4] 2> 178391 INFO (Listener at localhost/40225) [ ] o.e.j.s.h.ContextHandler Started o.e.j.w.WebAppContext@20d026c3{datanode,/,file:///home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/jetty-localhost-45161-hadoop-hdfs-3_2_4-tests_jar-_-any-6462550433359838999/webapp/,AVAILABLE}{jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.4-tests.jar!/webapps/datanode}
[junit4] 2> 178392 INFO (Listener at localhost/40225) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2a394b22{HTTP/1.1, (http/1.1)}{localhost:45161}
[junit4] 2> 178392 INFO (Listener at localhost/40225) [ ] o.e.j.s.Server Started @178419ms
[junit4] 2> 180851 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x8dd0f6e50a954076: Processing first storage report for DS-ba396eec-166b-4d7d-8efa-34122ef146ed from datanode DatanodeRegistration(127.0.0.1:44761, datanodeUuid=c0f77374-99cf-4da5-9f0a-36aea2254380, infoPort=43773, infoSecurePort=0, ipcPort=43949, storageInfo=lv=-57;cid=testClusterID;nsid=1970738846;c=1708471680498)
[junit4] 2> 180854 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x8dd0f6e50a954076: from storage DS-ba396eec-166b-4d7d-8efa-34122ef146ed node DatanodeRegistration(127.0.0.1:44761, datanodeUuid=c0f77374-99cf-4da5-9f0a-36aea2254380, infoPort=43773, infoSecurePort=0, ipcPort=43949, storageInfo=lv=-57;cid=testClusterID;nsid=1970738846;c=1708471680498), blocks: 0, hasStaleStorage: true, processing time: 3 msecs, invalidatedBlocks: 0
[junit4] 2> 180854 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1102c83712cb5018: Processing first storage report for DS-48a3cdc5-2ca4-4545-9df6-c1e421e7ca1e from datanode DatanodeRegistration(127.0.0.1:45775, datanodeUuid=1e480d6a-9cd5-483b-a5a8-e6a615170148, infoPort=38707, infoSecurePort=0, ipcPort=40225, storageInfo=lv=-57;cid=testClusterID;nsid=1970738846;c=1708471680498)
[junit4] 2> 180854 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1102c83712cb5018: from storage DS-48a3cdc5-2ca4-4545-9df6-c1e421e7ca1e node DatanodeRegistration(127.0.0.1:45775, datanodeUuid=1e480d6a-9cd5-483b-a5a8-e6a615170148, infoPort=38707, infoSecurePort=0, ipcPort=40225, storageInfo=lv=-57;cid=testClusterID;nsid=1970738846;c=1708471680498), blocks: 0, hasStaleStorage: true, processing time: 0 msecs, invalidatedBlocks: 0
[junit4] 2> 180854 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1102c83712cb5018: Processing first storage report for DS-e0835f21-b3bd-4586-be45-ac2863e61f3e from datanode DatanodeRegistration(127.0.0.1:45775, datanodeUuid=1e480d6a-9cd5-483b-a5a8-e6a615170148, infoPort=38707, infoSecurePort=0, ipcPort=40225, storageInfo=lv=-57;cid=testClusterID;nsid=1970738846;c=1708471680498)
[junit4] 2> 180854 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1102c83712cb5018: from storage DS-e0835f21-b3bd-4586-be45-ac2863e61f3e node DatanodeRegistration(127.0.0.1:45775, datanodeUuid=1e480d6a-9cd5-483b-a5a8-e6a615170148, infoPort=38707, infoSecurePort=0, ipcPort=40225, storageInfo=lv=-57;cid=testClusterID;nsid=1970738846;c=1708471680498), blocks: 0, hasStaleStorage: false, processing time: 0 msecs, invalidatedBlocks: 0
[junit4] 2> 180854 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x8dd0f6e50a954076: Processing first storage report for DS-c6b545d3-0d22-4c4d-9d73-7a0698939fed from datanode DatanodeRegistration(127.0.0.1:44761, datanodeUuid=c0f77374-99cf-4da5-9f0a-36aea2254380, infoPort=43773, infoSecurePort=0, ipcPort=43949, storageInfo=lv=-57;cid=testClusterID;nsid=1970738846;c=1708471680498)
[junit4] 2> 180854 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x8dd0f6e50a954076: from storage DS-c6b545d3-0d22-4c4d-9d73-7a0698939fed node DatanodeRegistration(127.0.0.1:44761, datanodeUuid=c0f77374-99cf-4da5-9f0a-36aea2254380, infoPort=43773, infoSecurePort=0, ipcPort=43949, storageInfo=lv=-57;cid=testClusterID;nsid=1970738846;c=1708471680498), blocks: 0, hasStaleStorage: false, processing time: 0 msecs, invalidatedBlocks: 0
[junit4] 2> 181074 INFO (Listener at localhost/43949) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 181081 INFO (Listener at localhost/43949) [ ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 181313 INFO (Listener at localhost/43949) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.4
[junit4] 2> 181313 WARN (Listener at localhost/43949) [ ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 181316 INFO (Listener at localhost/43949) [ ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 181319 INFO (Listener at localhost/43949) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 181454 WARN (Listener at localhost/43949) [ ] o.a.s.c.CoreContainer Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/userfiles]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/userfiles" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/userfiles" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:1.8.0_362]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:886) ~[?:1.8.0_362]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:549) ~[?:1.8.0_362]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_362]
[junit4] 2> at sun.nio.fs.UnixPath.checkWrite(UnixPath.java:801) ~[?:?]
[junit4] 2> at sun.nio.fs.UnixFileSystemProvider.createDirectory(UnixFileSystemProvider.java:376) ~[?:?]
[junit4] 2> at java.nio.file.Files.createDirectory(Files.java:674) ~[?:1.8.0_362]
[junit4] 2> at java.nio.file.Files.createAndCheckIsDirectory(Files.java:781) ~[?:1.8.0_362]
[junit4] 2> at java.nio.file.Files.createDirectories(Files.java:727) ~[?:1.8.0_362]
[junit4] 2> at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:379) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:344) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:183) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:156) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:162) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:840) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:830) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:677) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:666) ~[java/:?]
[junit4] 2> at org.apache.solr.update.TestHdfsUpdateLog.beforeClass(TestHdfsUpdateLog.java:64) ~[test/:?]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_362]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_362]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_362]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_362]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1758) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:886) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:902) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:80) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) ~[junit-4.13.1.jar:4.13.1]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) ~[junit-4.13.1.jar:4.13.1]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:390) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:850) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:750) [?:1.8.0_362]
[junit4] 2> 181456 WARN (Listener at localhost/43949) [ ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:1.8.0_362]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:886) ~[?:1.8.0_362]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:549) ~[?:1.8.0_362]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_362]
[junit4] 2> at java.io.File.mkdir(File.java:1350) ~[?:1.8.0_362]
[junit4] 2> at java.io.File.mkdirs(File.java:1382) ~[?:1.8.0_362]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:561) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:81) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:78) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:693) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:184) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:156) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:162) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:840) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:830) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:677) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:666) ~[java/:?]
[junit4] 2> at org.apache.solr.update.TestHdfsUpdateLog.beforeClass(TestHdfsUpdateLog.java:64) ~[test/:?]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_362]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_362]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_362]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_362]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1758) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:886) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:902) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:80) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) ~[junit-4.13.1.jar:4.13.1]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) ~[junit-4.13.1.jar:4.13.1]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:390) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:850) ~[randomizedtesting-runner-2.8.1.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:750) [?:1.8.0_362]
[junit4] 2> 181457 INFO (Listener at localhost/43949) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 181458 WARN (Listener at localhost/43949) [ ] 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> 181459 WARN (Listener at localhost/43949) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@e560a41[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 181459 WARN (Listener at localhost/43949) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@e560a41[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 181464 WARN (Listener at localhost/43949) [ ] 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> 181468 WARN (Listener at localhost/43949) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@773f2f4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 181468 WARN (Listener at localhost/43949) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@773f2f4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 181470 WARN (Listener at localhost/43949) [ ] 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> 181502 INFO (Listener at localhost/43949) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@8216549
[junit4] 2> 181528 INFO (Listener at localhost/43949) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@8216549
[junit4] 2> 181528 INFO (Listener at localhost/43949) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@8216549
[junit4] 2> 181530 INFO (coreLoadExecutor-1641-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
[junit4] 2> 181552 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 181571 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.4
[junit4] 2> 181571 WARN (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 181575 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 181578 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 181586 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 181586 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@8216549
[junit4] 2> 181596 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:39723/solr_hdfs_home
[junit4] 2> 181596 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 181596 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1], dataDir=[hdfs://localhost:39723/solr_hdfs_home/collection1//home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/solr.update.TestHdfsUpdateLog_9510D528F8A25D6A-001/data-dir-28-001/]
[junit4] 2> 181597 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:39723/solr_hdfs_home/collection1//home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/solr.update.TestHdfsUpdateLog_9510D528F8A25D6A-001/data-dir-28-001/snapshot_metadata
[junit4] 2> 181614 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true]
[junit4] 2> 181614 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [33554432] will allocate [1] slabs and use ~[33554432] bytes
[junit4] 2> 181614 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.HdfsDirectoryFactory Creating new global HDFS BlockCache
[junit4] 2> 181700 WARN (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.s.h.HdfsDirectory HDFS support in Solr has been deprecated as of 8.6. See SOLR-14021 for details.
[junit4] 2> 181700 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 181709 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:39723/solr_hdfs_home/collection1//home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/solr.update.TestHdfsUpdateLog_9510D528F8A25D6A-001/data-dir-28-001
[junit4] 2> 181726 WARN (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.s.h.HdfsDirectory HDFS support in Solr has been deprecated as of 8.6. See SOLR-14021 for details.
[junit4] 2> 181800 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:39723/solr_hdfs_home/collection1//home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/solr.update.TestHdfsUpdateLog_9510D528F8A25D6A-001/data-dir-28-001/index
[junit4] 2> 181810 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true]
[junit4] 2> 181810 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [33554432] will allocate [1] slabs and use ~[33554432] bytes
[junit4] 2> 181817 WARN (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.s.h.HdfsDirectory HDFS support in Solr has been deprecated as of 8.6. See SOLR-14021 for details.
[junit4] 2> 181817 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 181818 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=94.7001953125, floorSegmentMB=1.439453125, forceMergeDeletesPctAllowed=23.90159636040209, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=46.0532049790148
[junit4] 2> 182947 WARN (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 183075 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 183075 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=hdfs://localhost:39723/solr/shard1 defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 183075 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 183099 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 183099 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 183102 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=43, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 183257 WARN (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf ; switching to use InMemory storage instead.
[junit4] 2> 183260 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 183261 INFO (coreLoadExecutor-1641-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1791462413283885056
[junit4] 2> 183264 INFO (searcherExecutor-1643-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 183265 INFO (Listener at localhost/43949) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 183700 INFO (TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[9510D528F8A25D6A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testFSThreadSafety
[junit4] 2> 183700 INFO (TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[9510D528F8A25D6A]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 183715 INFO (TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[9510D528F8A25D6A]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 15
[junit4] 2> 183814 INFO (searcherExecutor-1643-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 183814 INFO (TEST-TestHdfsUpdateLog.testFSThreadSafety-seed#[9510D528F8A25D6A]) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 98
[junit4] 2> 183877 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0 (1791462413871087616)]} 0 55
[junit4] 2> 183891 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1 (1791462413941342208)]} 0 2
[junit4] 2> 183912 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2 (1791462413954973696)]} 0 10
[junit4] 2> 183924 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3 (1791462413976993792)]} 0 1
[junit4] 2> 183936 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4 (1791462413989576704)]} 0 1
[junit4] 2> 183948 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5 (1791462414002159616)]} 0 1
[junit4] 2> 184079 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6 (1791462414014742528)]} 0 120
[junit4] 2> 184096 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7 (1791462414156300288)]} 0 4
[junit4] 2> 184113 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8 (1791462414169931776)]} 0 6
[junit4] 2> 184126 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9 (1791462414188806144)]} 0 2
[junit4] 2> 184139 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10 (1791462414201389056)]} 0 2
[junit4] 2> 184160 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11 (1791462414216069120)]} 0 9
[junit4] 2> 184174 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[12 (1791462414237040640)]} 0 3
[junit4] 2> 184194 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[13 (1791462414251720704)]} 0 9
[junit4] 2> 184208 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[14 (1791462414272692224)]} 0 3
[junit4] 2> 184224 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15 (1791462414287372288)]} 0 5
[junit4] 2> 184245 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[16 (1791462414314635264)]} 0 1
[junit4] 2> 184266 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[17 (1791462414331412480)]} 0 6
[junit4] 2> 184284 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[18 (1791462414349238272)]} 0 6
[junit4] 2> 184303 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[19 (1791462414373355520)]} 0 2
[junit4] 2> 184314 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[20 (1791462414386987008)]} 0 1
[junit4] 2> 184435 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[21 (1791462414399569920)]} 0 109
[junit4] 2> 184447 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[22 (1791462414525399040)]} 0 1
[junit4] 2> 184458 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[23 (1791462414539030528)]} 0 0
[junit4] 2> 184470 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[24 (1791462414550564864)]} 0 0
[junit4] 2> 184482 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[25 (1791462414563147776)]} 0 0
[junit4] 2> 184493 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[26 (1791462414575730688)]} 0 0
[junit4] 2> 184505 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[27 (1791462414587265024)]} 0 1
[junit4] 2> 184516 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[28 (1791462414598799360)]} 0 0
[junit4] 2> 184527 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[29 (1791462414610333696)]} 0 0
[junit4] 2> 184538 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[30 (1791462414622916608)]} 0 0
[junit4] 2> 184550 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[31 (1791462414634450944)]} 0 0
[junit4] 2> 184563 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[32 (1791462414648082432)]} 0 0
[junit4] 2> 184574 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[33 (1791462414659616768)]} 0 1
[junit4] 2> 184585 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[34 (1791462414672199680)]} 0 0
[junit4] 2> 184597 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[35 (1791462414683734016)]} 0 1
[junit4] 2> 184608 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[36 (1791462414695268352)]} 0 0
[junit4] 2> 184620 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[37 (1791462414706802688)]} 0 1
[junit4] 2> 184631 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[38 (1791462414720434176)]} 0 1
[junit4] 2> 184902 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[39 (1791462414731968512)]} 0 259
[junit4] 2> 184920 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[40 (1791462415020326912)]} 0 3
[junit4] 2> 184932 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[41 (1791462415035006976)]} 0 1
[junit4] 2> 184944 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[42 (1791462415047589888)]} 0 1
[junit4] 2> 184956 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[43 (1791462415060172800)]} 0 1
[junit4] 2> 184969 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[44 (1791462415071707136)]} 0 2
[junit4] 2> 184987 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[45 (1791462415092678656)]} 0 1
[junit4] 2> 185000 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[46 (1791462415104212992)]} 0 2
[junit4] 2> 185020 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[47 (1791462415119941632)]} 0 8
[junit4] 2> 185032 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[48 (1791462415139864576)]} 0 1
[junit4] 2> 185062 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[49 (1791462415151398912)]} 0 19
[junit4] 2> 185074 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[50 (1791462415183904768)]} 0 1
[junit4] 2> 185086 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[51 (1791462415196487680)]} 0 1
[junit4] 2> 185098 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[52 (1791462415209070592)]} 0 1
[junit4] 2> 185109 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[53 (1791462415220604928)]} 0 0
[junit4] 2> 185137 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[54 (1791462415245770752)]} 0 4
[junit4] 2> 185149 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[55 (1791462415261499392)]} 0 1
[junit4] 2> 185160 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[56 (1791462415274082304)]} 0 0
[junit4] 2> 185171 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[57 (1791462415286665216)]} 0 0
[junit4] 2> 185213 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[58 (1791462415329656832)]} 0 1
[junit4] 2> 185229 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[59 (1791462415346434048)]} 0 0
[junit4] 2> 185241 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[60 (1791462415359016960)]} 0 0
[junit4] 2> 185253 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[61 (1791462415371599872)]} 0 1
[junit4] 2> 185265 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[62 (1791462415384182784)]} 0 1
[junit4] 2> 185276 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[63 (1791462415395717120)]} 0 0
[junit4] 2> 185287 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[64 (1791462415408300032)]} 0 0
[junit4] 2> 185299 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[65 (1791462415419834368)]} 0 0
[junit4] 2> 185342 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[66 (1791462415431368704)]} 0 33
[junit4] 2> 185440 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[67 (1791462415567683584)]} 0 4
[junit4] 2> 185453 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[68 (1791462415580266496)]} 0 2
[junit4] 2> 185464 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[69 (1791462415592849408)]} 0 0
[junit4] 2> 185476 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[70 (1791462415605432320)]} 0 1
[junit4] 2> 185487 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[71 (1791462415618015232)]} 0 0
[junit4] 2> 185499 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[72 (1791462415629549568)]} 0 1
[junit4] 2> 185510 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[73 (1791462415641083904)]} 0 1
[junit4] 2> 185522 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[74 (1791462415653666816)]} 0 0
[junit4] 2> 185536 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[75 (1791462415665201152)]} 0 3
[junit4] 2> 185551 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[76 (1791462415679881216)]} 0 4
[junit4] 2> 185564 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[77 (1791462415695609856)]} 0 2
[junit4] 2> 185575 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[78 (1791462415709241344)]} 0 1
[junit4] 2> 185588 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[79 (1791462415722872832)]} 0 0
[junit4] 2> 185600 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[80 (1791462415734407168)]} 0 1
[junit4] 2> 185902 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[81 (1791462416052125696)]} 0 0
[junit4] 2> 185914 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[82 (1791462416064708608)]} 0 0
[junit4] 2> 185925 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[83 (1791462416076242944)]} 0 0
[junit4] 2> 185938 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[84 (1791462416088825856)]} 0 2
[junit4] 2> 185950 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[85 (1791462416102457344)]} 0 1
[junit4] 2> 185962 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[86 (1791462416115040256)]} 0 1
[junit4] 2> 185974 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[87 (1791462416126574592)]} 0 1
[junit4] 2> 185985 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[88 (1791462416139157504)]} 0 0
[junit4] 2> 185999 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[89 (1791462416150691840)]} 0 3
[junit4] 2> 186010 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[90 (1791462416165371904)]} 0 0
[junit4] 2> 186021 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[91 (1791462416177954816)]} 0 0
[junit4] 2> 186033 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[92 (1791462416189489152)]} 0 1
[junit4] 2> 186045 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[93 (1791462416202072064)]} 0 0
[junit4] 2> 186056 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[94 (1791462416213606400)]} 0 0
[junit4] 2> 186175 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[95 (1791462416226189312)]} 0 108
[junit4] 2> 186188 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[96 (1791462416350969856)]} 0 1
[junit4] 2> 186199 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[97 (1791462416364601344)]} 0 0
[junit4] 2> 186211 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[98 (1791462416376135680)]} 0 1
[junit4] 2> 186230 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[99 (1791462416396058624)]} 0 1
[junit4] 2> 186241 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[100 (1791462416407592960)]} 0 0
[junit4] 2> 186253 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[101 (1791462416420175872)]} 0 0
[junit4] 2> 186264 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[102 (1791462416431710208)]} 0 1
[junit4] 2> 186275 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[103 (1791462416444293120)]} 0 0
[junit4] 2> 186286 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[104 (1791462416455827456)]} 0 0
[junit4] 2> 186297 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[105 (1791462416467361792)]} 0 0
[junit4] 2> 186308 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[106 (1791462416478896128)]} 0 0
[junit4] 2> 186322 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[107 (1791462416490430464)]} 0 2
[junit4] 2> 186333 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[108 (1791462416504061952)]} 0 0
[junit4] 2> 186344 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[109 (1791462416515596288)]} 0 0
[junit4] 2> 186357 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[110 (1791462416529227776)]} 0 1
[junit4] 2> 186368 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[111 (1791462416540762112)]} 0 0
[junit4] 2> 186382 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[112 (1791462416553345024)]} 0 3
[junit4] 2> 186393 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[113 (1791462416566976512)]} 0 0
[junit4] 2> 186405 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[114 (1791462416579559424)]} 0 1
[junit4] 2> 186704 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[115 (1791462416889937920)]} 0 4
[junit4] 2> 186716 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[116 (1791462416905666560)]} 0 0
[junit4] 2> 186727 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[117 (1791462416917200896)]} 0 1
[junit4] 2> 186740 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[118 (1791462416929783808)]} 0 2
[junit4] 2> 186751 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[119 (1791462416942366720)]} 0 0
[junit4] 2> 186763 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[120 (1791462416954949632)]} 0 0
[junit4] 2> 186774 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[121 (1791462416966483968)]} 0 0
[junit4] 2> 186785 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[122 (1791462416978018304)]} 0 0
[junit4] 2> 186799 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[123 (1791462416990601216)]} 0 3
[junit4] 2> 186816 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[124 (1791462417005281280)]} 0 6
[junit4] 2> 186828 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[125 (1791462417022058496)]} 0 1
[junit4] 2> 186840 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[126 (1791462417035689984)]} 0 0
[junit4] 2> 186851 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[127 (1791462417047224320)]} 0 0
[junit4] 2> 186863 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[128 (1791462417059807232)]} 0 0
[junit4] 2> 186874 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[129 (1791462417071341568)]} 0 0
[junit4] 2> 186890 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[130 (1791462417082875904)]} 0 5
[junit4] 2> 186902 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[131 (1791462417099653120)]} 0 1
[junit4] 2> 186913 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[132 (1791462417112236032)]} 0 0
[junit4] 2> 186924 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[133 (1791462417123770368)]} 0 1
[junit4] 2> 186936 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[134 (1791462417136353280)]} 0 1
[junit4] 2> 186947 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[135 (1791462417148936192)]} 0 0
[junit4] 2> 186962 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[136 (1791462417164664832)]} 0 0
[junit4] 2> 186975 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[137 (1791462417176199168)]} 0 1
[junit4] 2> 186986 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[138 (1791462417188782080)]} 0 0
[junit4] 2> 186997 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[139 (1791462417200316416)]} 0 0
[junit4] 2> 187008 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[140 (1791462417211850752)]} 0 0
[junit4] 2> 187019 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[141 (1791462417223385088)]} 0 0
[junit4] 2> 187030 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[142 (1791462417234919424)]} 0 0
[junit4] 2> 187041 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[143 (1791462417247502336)]} 0 0
[junit4] 2> 187053 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[144 (1791462417259036672)]} 0 0
[junit4] 2> 187064 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[145 (1791462417270571008)]} 0 0
[junit4] 2> 187076 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[146 (1791462417283153920)]} 0 0
[junit4] 2> 187087 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[147 (1791462417294688256)]} 0 0
[junit4] 2> 187099 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[148 (1791462417307271168)]} 0 0
[junit4] 2> 187110 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[149 (1791462417319854080)]} 0 0
[junit4] 2> 187121 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[150 (1791462417331388416)]} 0 0
[junit4] 2> 187133 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[151 (1791462417342922752)]} 0 0
[junit4] 2> 187144 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[152 (1791462417354457088)]} 0 0
[junit4] 2> 187156 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[153 (1791462417367040000)]} 0 1
[junit4] 2> 187167 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[154 (1791462417379622912)]} 0 0
[junit4] 2> 187179 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[155 (1791462417391157248)]} 0 0
[junit4] 2> 187190 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[156 (1791462417402691584)]} 0 0
[junit4] 2> 187202 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[157 (1791462417415274496)]} 0 1
[junit4] 2> 187213 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[158 (1791462417426808832)]} 0 0
[junit4] 2> 187225 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[159 (1791462417438343168)]} 0 1
[junit4] 2> 187236 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[160 (1791462417450926080)]} 0 0
[junit4] 2> 187248 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[161 (1791462417462460416)]} 0 1
[junit4] 2> 187260 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[162 (1791462417475043328)]} 0 1
[junit4] 2> 187271 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[163 (1791462417487626240)]} 0 0
[junit4] 2> 187283 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[164 (1791462417499160576)]} 0 1
[junit4] 2> 187295 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[165 (1791462417511743488)]} 0 1
[junit4] 2> 187306 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[166 (1791462417524326400)]} 0 0
[junit4] 2> 187317 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[167 (1791462417536909312)]} 0 0
[junit4] 2> 187329 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[168 (1791462417548443648)]} 0 1
[junit4] 2> 187341 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[169 (1791462417561026560)]} 0 0
[junit4] 2> 187352 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[170 (1791462417572560896)]} 0 0
[junit4] 2> 187364 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[171 (1791462417584095232)]} 0 1
[junit4] 2> 187384 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[172 (1791462417607163904)]} 0 0
[junit4] 2> 187395 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[173 (1791462417618698240)]} 0 0
[junit4] 2> 187406 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[174 (1791462417630232576)]} 0 0
[junit4] 2> 187417 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[175 (1791462417641766912)]} 0 0
[junit4] 2> 187428 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[176 (1791462417653301248)]} 0 0
[junit4] 2> 187445 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[177 (1791462417664835584)]} 0 6
[junit4] 2> 187456 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[178 (1791462417681612800)]} 0 1
[junit4] 2> 187467 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[179 (1791462417694195712)]} 0 0
[junit4] 2> 187481 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[180 (1791462417705730048)]} 0 2
[junit4] 2> 187492 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[181 (1791462417719361536)]} 0 0
[junit4] 2> 187503 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[182 (1791462417730895872)]} 0 0
[junit4] 2> 187514 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[183 (1791462417742430208)]} 0 0
[junit4] 2> 187525 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[184 (1791462417753964544)]} 0 0
[junit4] 2> 187535 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[185 (1791462417765498880)]} 0 0
[junit4] 2> 187546 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[186 (1791462417777033216)]} 0 0
[junit4] 2> 187572 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[187 (1791462417787518976)]} 0 15
[junit4] 2> 187584 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[188 (1791462417815830528)]} 0 0
[junit4] 2> 187595 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[189 (1791462417827364864)]} 0 0
[junit4] 2> 187606 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[190 (1791462417838899200)]} 0 0
[junit4] 2> 187617 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[191 (1791462417850433536)]} 0 0
[junit4] 2> 187628 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[192 (1791462417861967872)]} 0 0
[junit4] 2> 187639 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[193 (1791462417873502208)]} 0 0
[junit4] 2> 187650 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[194 (1791462417885036544)]} 0 0
[junit4] 2> 187661 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[195 (1791462417897619456)]} 0 0
[junit4] 2> 187672 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[196 (1791462417909153792)]} 0 0
[junit4] 2> 187683 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[197 (1791462417920688128)]} 0 0
[junit4] 2> 187694 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[198 (1791462417932222464)]} 0 0
[junit4] 2> 187705 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[199 (1791462417943756800)]} 0 0
[junit4] 2> 187716 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[200 (1791462417955291136)]} 0 0
[junit4] 2> 187727 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[201 (1791462417966825472)]} 0 0
[junit4] 2> 187738 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[202 (1791462417978359808)]} 0 0
[junit4] 2> 187749 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[203 (1791462417989894144)]} 0 0
[junit4] 2> 187760 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[204 (1791462418000379904)]} 0 0
[junit4] 2> 187771 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[205 (1791462418011914240)]} 0 0
[junit4] 2> 187782 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[206 (1791462418023448576)]} 0 0
[junit4] 2> 187793 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[207 (1791462418034982912)]} 0 0
[junit4] 2> 187804 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[208 (1791462418046517248)]} 0 0
[junit4] 2> 187816 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[209 (1791462418058051584)]} 0 1
[junit4] 2> 187827 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[210 (1791462418070634496)]} 0 0
[junit4] 2> 187838 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[211 (1791462418082168832)]} 0 0
[junit4] 2> 187849 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[212 (1791462418093703168)]} 0 0
[junit4] 2> 187860 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[213 (1791462418105237504)]} 0 0
[junit4] 2> 187871 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[214 (1791462418116771840)]} 0 0
[junit4] 2> 187882 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[215 (1791462418129354752)]} 0 0
[junit4] 2> 187893 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[216 (1791462418140889088)]} 0 0
[junit4] 2> 187904 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[217 (1791462418152423424)]} 0 0
[junit4] 2> 187915 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[218 (1791462418163957760)]} 0 0
[junit4] 2> 187927 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[219 (1791462418175492096)]} 0 0
[junit4] 2> 187938 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[220 (1791462418187026432)]} 0 0
[junit4] 2> 187949 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[221 (1791462418198560768)]} 0 0
[junit4] 2> 187960 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[222 (1791462418210095104)]} 0 0
[junit4] 2> 187971 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[223 (1791462418221629440)]} 0 0
[junit4] 2> 187981 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[224 (1791462418233163776)]} 0 0
[junit4] 2> 187993 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[225 (1791462418244698112)]} 0 0
[junit4] 2> 188003 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[226 (1791462418256232448)]} 0 0
[junit4] 2> 188014 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[227 (1791462418267766784)]} 0 0
[junit4] 2> 188025 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[228 (1791462418279301120)]} 0 0
[junit4] 2> 188036 INFO (Thread-354) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[229 (1791462418289786880)]} 0 0
[junit4] 2> 188047 INFO
[...truncated too long message...]
null
[junit4] 2> 718257 DEBUG (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718257 DEBUG (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718257 DEBUG (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718257 DEBUG (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718257 DEBUG (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718257 DEBUG (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718257 DEBUG (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718257 INFO (closeThreadPool-4859-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5ff0a489{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 718258 INFO (closeThreadPool-4859-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@40469f21{/_n/h,null,STOPPED}
[junit4] 2> 718258 INFO (closeThreadPool-4859-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718357 DEBUG (closeThreadPool-4699-thread-3-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718358 INFO (closeThreadPool-4859-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4ee6951{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 718358 INFO (closeThreadPool-4859-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@38ad177d{/_n/h,null,STOPPED}
[junit4] 2> 718358 INFO (closeThreadPool-4859-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 718359 INFO (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]) [ ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-159 after mutting 0 log messages
[junit4] 2> 718359 INFO (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]) [ ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-160 for ERROR logs matching regex: ignore_exception
[junit4] 2> 718360 INFO (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 718461 DEBUG (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718562 DEBUG (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 718563 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> 12 /solr/collections/collection1/terms/shard1_0
[junit4] 2> 9 /solr/collections/collection1/terms/shard1_1
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 5 /solr/packages.json
[junit4] 2> 5 /solr/security.json
[junit4] 2> 5 /solr/collections/collection1/collectionprops.json
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2> 5 /solr/collections/collection1/terms/shard1
[junit4] 2> 5 /solr/collections/collection1/terms/shard2
[junit4] 2> 2 /solr/collections/control_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 193 /solr/collections/collection1/state.json
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 7 /solr/collections/control_collection/state.json
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 80 /solr/overseer/queue
[junit4] 2> 31 /solr/live_nodes
[junit4] 2> 22 /solr/overseer/collection-queue-work
[junit4] 2> 13 /solr/collections/collection1/state.json
[junit4] 2> 7 /solr/collections
[junit4] 2> 2 /solr/collections/control_collection/state.json
[junit4] 2>
[junit4] 2> 718567 INFO (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:35989
[junit4] 2> 718567 INFO (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:35989
[junit4] 2> 718567 INFO (TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[9510D528F8A25D6A]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 35989
[junit4] 2> 718568 INFO (SUITE-ShardSplitTest-seed#[9510D528F8A25D6A]-worker) [ ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-160 after mutting 0 log messages
[junit4] 2> 718568 INFO (SUITE-ShardSplitTest-seed#[9510D528F8A25D6A]-worker) [ ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-161 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/J3/temp/solr.cloud.api.collections.ShardSplitTest_9510D528F8A25D6A-001
[junit4] 2> Feb 20, 2024 11:37:07 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): {rnd_b=PostingsFormat(name=Direct), a_t=Lucene84, _root_=Lucene84, id=PostingsFormat(name=Direct), foo_s=Lucene84}, docValues:{_version_=DocValuesFormat(name=Direct), a_i=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=571, maxMBSortInHeap=5.27148399294581, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=es-EC, timezone=Asia/Manila
[junit4] 2> NOTE: Linux 5.4.0-171-generic amd64/Temurin 1.8.0_362 (64-bit)/cpus=4,threads=1,free=163648776,total=515899392
[junit4] 2> NOTE: All tests run in this JVM: [LegacyCloudClusterPropTest, TestMinMaxOnMultiValuedField, TestRecovery, PhrasesIdentificationComponentTest, TestQuerySenderNoQuery, TestDistributedGrouping, TestCloudPhrasesIdentificationComponent, TestCloudRecovery2, HdfsChaosMonkeyNothingIsSafeTest, TestHdfsUpdateLog, TestJavabinTupleStreamParser, DistributedQueueTest, TestConfigOverlay, ZkShardTermsTest, TestManagedSynonymGraphFilterFactory, DateMathParserTest, TestSolr4Spatial, TokenizerChainTest, DebugComponentTest, ChaosMonkeySafeLeaderTest, TestInPlaceUpdatesStandalone, MetricTriggerTest, SolrMetricManagerTest, ClassificationUpdateProcessorFactoryTest, DefaultValueUpdateProcessorTest, TestFastLRUCache, NodeLostTriggerTest, SolrPluginUtilsTest, HighlighterTest, TermVectorComponentTest, ShardSplitTest]
[junit4] Completed [264/959 (2!)] on J3 in 347.74s, 11 tests, 1 error, 3 skipped <<< FAILURES!
[...truncated 56682 lines...]
[JENKINS] Lucene » Lucene-Solr-Tests-8.11 - Build # 563 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.11/563/
2 tests failed.
FAILED: org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitMixedReplicaTypesLink
Error Message:
Timeout occurred while waiting response from server at: http://127.0.0.1:34779/qfgs/s
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:34779/qfgs/s
at __randomizedtesting.SeedInfo.seed([88FD910D61D611DB:B4935854C40EBA42]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:692)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:369)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:297)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1188)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:225)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:242)
at org.apache.solr.cloud.api.collections.ShardSplitTest.doSplitMixedReplicaTypes(ShardSplitTest.java:365)
at org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitMixedReplicaTypesLink(ShardSplitTest.java:356)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1084)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1055)
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)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:571)
... 57 more
FAILED: junit.framework.TestSuite.org.apache.solr.core.backup.repository.HdfsBackupRepositoryIntegrationTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.core.backup.repository.HdfsBackupRepositoryIntegrationTest: 1) Thread[id=35747, name=Command processor, state=WAITING, group=TGRP-HdfsBackupRepositoryIntegrationTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.processQueue(BPServiceActor.java:1291) at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.run(BPServiceActor.java:1275)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.backup.repository.HdfsBackupRepositoryIntegrationTest:
1) Thread[id=35747, name=Command processor, state=WAITING, group=TGRP-HdfsBackupRepositoryIntegrationTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.processQueue(BPServiceActor.java:1291)
at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.run(BPServiceActor.java:1275)
at __randomizedtesting.SeedInfo.seed([88FD910D61D611DB]:0)
Build Log:
[...truncated 14287 lines...]
[junit4] Suite: org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4] 2> 622354 INFO (SUITE-ShardSplitTest-seed#[88FD910D61D611DB]-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> 622354 INFO (SUITE-ShardSplitTest-seed#[88FD910D61D611DB]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 622355 INFO (SUITE-ShardSplitTest-seed#[88FD910D61D611DB]-worker) [ ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-118 after mutting 0 log messages
[junit4] 2> 622355 INFO (SUITE-ShardSplitTest-seed#[88FD910D61D611DB]-worker) [ ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-119 for ERROR logs matching regex: ignore_exception
[junit4] 2> 622356 INFO (SUITE-ShardSplitTest-seed#[88FD910D61D611DB]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_88FD910D61D611DB-001/data-dir-56-001
[junit4] 2> 622356 WARN (SUITE-ShardSplitTest-seed#[88FD910D61D611DB]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=90 numCloses=90
[junit4] 2> 622356 INFO (SUITE-ShardSplitTest-seed#[88FD910D61D611DB]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 622358 INFO (SUITE-ShardSplitTest-seed#[88FD910D61D611DB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 622358 INFO (SUITE-ShardSplitTest-seed#[88FD910D61D611DB]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /qfgs/s
[junit4] 2> 622362 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-119 after mutting 0 log messages
[junit4] 2> 622362 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-120 for ERROR logs matching regex: ignore_exception
[junit4] 2> 622362 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 622363 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 622363 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 622364 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 622463 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer start zk server on port: 46007
[junit4] 2> 622463 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:46007
[junit4] 2> 622463 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46007
[junit4] 2> 622463 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46007
[junit4] 2> 622464 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 622465 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 622470 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 622470 DEBUG (zkConnectionManagerCallback-7004-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@782ed17b name: ZooKeeperConnection Watcher:127.0.0.1:46007 got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 622470 INFO (zkConnectionManagerCallback-7004-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 622470 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 622470 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 622474 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 622479 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 622481 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 622482 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 622482 DEBUG (zkConnectionManagerCallback-7006-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3aafb89d name: ZooKeeperConnection Watcher:127.0.0.1:46007/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 622482 INFO (zkConnectionManagerCallback-7006-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 622482 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 622483 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 622483 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterprops.json
[junit4] 2> 622484 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 622484 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 622485 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 622485 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 622486 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 622486 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 622487 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 622487 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 622488 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 622488 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 622488 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 622489 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 622489 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 622489 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 622491 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 622491 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 622492 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 622492 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 622493 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 622493 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 622494 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 622494 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 622495 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 622625 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 622625 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 622625 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] 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> 622634 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 622634 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 622634 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 622634 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ca126c7{/qfgs/s,null,AVAILABLE}
[junit4] 2> 622635 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2cdd4da7{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42369}
[junit4] 2> 622635 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.e.j.s.Server Started @622678ms
[junit4] 2> 622635 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_88FD910D61D611DB-001/tempDir-001/control/data, replicaType=NRT, hostContext=/qfgs/s, hostPort=42369, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_88FD910D61D611DB-001/control-001/cores}
[junit4] 2> 622635 ERROR (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 622635 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 622635 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.11.4
[junit4] 2> 622635 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 622635 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr
[junit4] 2> 622635 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2024-02-21T04:34:14.981Z
[junit4] 2> 622635 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 622638 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 622638 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 622641 DEBUG (zkConnectionManagerCallback-7008-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@696df38a name: ZooKeeperConnection Watcher:127.0.0.1:46007/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 622642 INFO (zkConnectionManagerCallback-7008-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 622642 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 622642 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 622743 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 622743 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 622743 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_88FD910D61D611DB-001/control-001/solr.xml
[junit4] 2> 622746 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 622746 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 622747 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@34b4589b, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 622910 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 622911 WARN (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2155f905[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 622911 WARN (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2155f905[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 622914 WARN (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@50299197[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 622914 WARN (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@50299197[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 622915 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46007/solr
[junit4] 2> 622915 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 622916 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 622917 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 622917 DEBUG (zkConnectionManagerCallback-7019-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@26e09f6e name: ZooKeeperConnection Watcher:127.0.0.1:46007 got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 622917 INFO (zkConnectionManagerCallback-7019-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 622917 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 622917 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 623018 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 623019 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 623020 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 623021 DEBUG (zkConnectionManagerCallback-7021-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@91d4141 name: ZooKeeperConnection Watcher:127.0.0.1:46007/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 623021 INFO (zkConnectionManagerCallback-7021-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 623021 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 623022 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 623022 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 623023 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 623024 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /overseer/async_ids
[junit4] 2> 623025 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 623025 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /node_roles
[junit4] 2> 623025 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /node_roles/data
[junit4] 2> 623026 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /node_roles/data/on
[junit4] 2> 623027 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /node_roles/data/off
[junit4] 2> 623028 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /node_roles/overseer
[junit4] 2> 623029 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /node_roles/overseer/allowed
[junit4] 2> 623030 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /node_roles/overseer/preferred
[junit4] 2> 623031 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /node_roles/overseer/disallowed
[junit4] 2> 623032 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /node_roles/coordinator
[junit4] 2> 623032 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /node_roles/coordinator/on
[junit4] 2> 623033 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /node_roles/coordinator/off
[junit4] 2> 623035 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 623035 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events
[junit4] 2> 623036 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/triggerState
[junit4] 2> 623037 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/nodeAdded
[junit4] 2> 623037 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/nodeLost
[junit4] 2> 623038 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 623039 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /autoscaling.json
[junit4] 2> 623039 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 623040 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/protwords.txt
[junit4] 2> 623041 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/managed-schema
[junit4] 2> 623042 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/solrconfig.xml
[junit4] 2> 623043 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/synonyms.txt
[junit4] 2> 623045 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_it.txt
[junit4] 2> 623046 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_cz.txt
[junit4] 2> 623047 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fr.txt
[junit4] 2> 623048 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_no.txt
[junit4] 2> 623049 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_ca.txt
[junit4] 2> 623051 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_sv.txt
[junit4] 2> 623052 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ca.txt
[junit4] 2> 623053 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hu.txt
[junit4] 2> 623054 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_gl.txt
[junit4] 2> 623055 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_bg.txt
[junit4] 2> 623056 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_th.txt
[junit4] 2> 623057 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ga.txt
[junit4] 2> 623059 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_da.txt
[junit4] 2> 623060 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_et.txt
[junit4] 2> 623061 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/userdict_ja.txt
[junit4] 2> 623062 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_ga.txt
[junit4] 2> 623063 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_id.txt
[junit4] 2> 623064 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_tr.txt
[junit4] 2> 623066 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ja.txt
[junit4] 2> 623067 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ar.txt
[junit4] 2> 623068 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ru.txt
[junit4] 2> 623070 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/hyphenations_ga.txt
[junit4] 2> 623071 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_de.txt
[junit4] 2> 623072 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_en.txt
[junit4] 2> 623074 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stemdict_nl.txt
[junit4] 2> 623075 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ro.txt
[junit4] 2> 623076 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hy.txt
[junit4] 2> 623078 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_eu.txt
[junit4] 2> 623079 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hi.txt
[junit4] 2> 623080 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stoptags_ja.txt
[junit4] 2> 623082 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_fr.txt
[junit4] 2> 623083 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fi.txt
[junit4] 2> 623084 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_pt.txt
[junit4] 2> 623085 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_lv.txt
[junit4] 2> 623087 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_el.txt
[junit4] 2> 623088 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_it.txt
[junit4] 2> 623089 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_nl.txt
[junit4] 2> 623091 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_es.txt
[junit4] 2> 623093 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fa.txt
[junit4] 2> 623094 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/stopwords.txt
[junit4] 2> 623095 WARN (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] 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> 623095 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 623096 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {legacyCloud=true}
[junit4] 2> 623096 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> []
[junit4] 2> 623097 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 623097 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ZkStateReader$AliasesManager Checking ZK for most up to date Aliases /aliases.json
[junit4] 2> 623097 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ZkStateReader$AliasesManager Aliases: cmp=-1, new definition is: Aliases{collectionAliases={}, collectionAliasProperties={}, zNodeVersion=0}
[junit4] 2> 623099 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 623100 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 623101 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42369_qfgs%2Fs
[junit4] 2> 623101 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 623102 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.Overseer Overseer (id=72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) starting
[junit4] 2> 623102 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 623103 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 623104 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 623106 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 623107 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42369_qfgs%2Fs
[junit4] 2> 623107 INFO (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42369_qfgs%2Fs
[junit4] 2> 623108 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes
[junit4] 2> 623108 DEBUG (zkCallback-7020-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0])
[junit4] 2> 623108 INFO (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 623108 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:42369_qfgs%2Fs]
[junit4] 2> 623108 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 #Completed tasks: 0
[junit4] 2> 623108 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor RunningTasks: []
[junit4] 2> 623108 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 623108 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 623108 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 623108 INFO (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: []
[junit4] 2> 623109 DEBUG (zkCallback-7020-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:42369_qfgs%2Fs] -> [127.0.0.1:42369_qfgs%2Fs]
[junit4] 2> 623109 DEBUG (OverseerAutoScalingTriggerThread-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [ ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events/.scheduled_maintenance
[junit4] 2> 623110 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.ZkController non-data nodes now []
[junit4] 2> 623111 DEBUG (OverseerAutoScalingTriggerThread-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [ ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events/.auto_add_replicas
[junit4] 2> 623113 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 623113 WARN (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] 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> 623137 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 623157 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34b4589b
[junit4] 2> 623164 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34b4589b
[junit4] 2> 623164 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34b4589b
[junit4] 2> 623165 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [n:127.0.0.1:42369_qfgs%2Fs ] 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/J3/temp/solr.cloud.api.collections.ShardSplitTest_88FD910D61D611DB-001/control-001/cores
[junit4] 2> 623178 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 623178 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 623179 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 623179 DEBUG (zkConnectionManagerCallback-7038-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7f4bd615 name: ZooKeeperConnection Watcher:127.0.0.1:46007/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 623179 INFO (zkConnectionManagerCallback-7038-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 623179 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 623180 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 623180 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 623180 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {legacyCloud=true}
[junit4] 2> 623181 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 623181 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:42369_qfgs%2Fs]
[junit4] 2> 623181 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 623181 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ZkStateReader$AliasesManager Checking ZK for most up to date Aliases /aliases.json
[junit4] 2> 623182 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.c.ZkStateReader$AliasesManager Aliases: cmp=-1, new definition is: Aliases{collectionAliases={}, collectionAliasProperties={}, zNodeVersion=0}
[junit4] 2> 623182 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46007/solr ready
[junit4] 2> 623186 INFO (qtp1843735856-10435) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.s.HttpSolrCall HttpSolrCall.init(http://127.0.0.1:42369/qfgs/s/admin/collections?action=CREATE&name=control_collection&collection.configName=conf1&createNodeSet=127.0.0.1%3A42369_qfgs%252Fs&numShards=1&nrtReplicas=1&wt=javabin&version=2)
[junit4] 2> 623187 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/collection-queue-work
[junit4] 2> 623187 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/collection-queue-work
[junit4] 2> 623188 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]]
[junit4] 2> 623188 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as running
[junit4] 2> 623188 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id: /overseer/collection-queue-work/qn-0000000000 message: {
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 623189 DEBUG (OverseerThreadFactory-7028-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000000
[junit4] 2> 623189 DEBUG (OverseerThreadFactory-7028-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.a.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 623190 DEBUG (OverseerThreadFactory-7028-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ZkStateReader$AliasesManager Checking ZK for most up to date Aliases /aliases.json
[junit4] 2> 623190 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 #Completed tasks: 0
[junit4] 2> 623190 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 623190 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 623190 DEBUG (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 623190 INFO (OverseerCollectionConfigSetProcessor-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 623190 DEBUG (OverseerThreadFactory-7028-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.c.ZkStateReader$AliasesManager Aliases: cmp=0, not overwriting ZK version.
[junit4] 2> 623190 INFO (OverseerThreadFactory-7028-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 623190 DEBUG (OverseerThreadFactory-7028-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.a.c.OverseerCollectionMessageHandler creating collections conf node /collections/control_collection
[junit4] 2> 623191 DEBUG (OverseerThreadFactory-7028-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.a.c.CreateCollectionCmd Check for collection zkNode: control_collection
[junit4] 2> 623191 DEBUG (OverseerThreadFactory-7028-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.a.c.CreateCollectionCmd Collection zkNode exists
[junit4] 2> 623192 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 623192 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 623192 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
[junit4] 2> 623193 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/control_collection/state.json
[junit4] 2> 623194 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 623294 DEBUG (OverseerThreadFactory-7028-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.a.c.CreateCollectionCmd Creating SolrCores for new collection control_collection, shardNames [shard1] , message : {
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 623296 DEBUG (OverseerThreadFactory-7028-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.a.c.CreateCollectionCmd Creating core control_collection_shard1_replica_n1 as part of shard shard1 of collection control_collection on 127.0.0.1:42369_qfgs%2Fs
[junit4] 2> 623296 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.s.HttpSolrCall HttpSolrCall.init(http://127.0.0.1:42369/qfgs/s/admin/cores?null)
[junit4] 2> 623297 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 623297 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 4 cores with initial capacity of 4
[junit4] 2> 623299 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 623300 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "base_url":"http://127.0.0.1:42369/qfgs/s",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 623300 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "base_url":"http://127.0.0.1:42369/qfgs/s",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 623301 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "base_url":"http://127.0.0.1:42369/qfgs/s",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}
[junit4] 2> 623301 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(control_collection//collections/control_collection/state.json/0 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "base_url":"http://127.0.0.1:42369/qfgs/s",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 623401 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/control_collection/state.json version: 0
[junit4] 2> 623402 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 624302 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Loading collection config from: [/collections/control_collection]
[junit4] 2> 624309 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.4
[junit4] 2> 624309 WARN (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 624313 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 624317 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 624343 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 624345 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34b4589b
[junit4] 2> 624345 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_88FD910D61D611DB-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.11/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_88FD910D61D611DB-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 624347 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=89.4775390625, floorSegmentMB=2.0693359375, forceMergeDeletesPctAllowed=7.8109720043662945, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5899166289278049, deletesPctAllowed=30.370242718691596
[junit4] 2> 624375 WARN (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 624415 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 624415 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 624417 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 624417 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 624418 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=99.615234375, floorSegmentMB=0.4677734375, forceMergeDeletesPctAllowed=15.619389562703637, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=20.555180385519616
[junit4] 2> 624422 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Loading collection config from: [/collections/control_collection]
[junit4] 2> 624423 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 624423 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 624424 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 624424 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1791481669767659520
[junit4] 2> 624426 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Waiting up to 60000ms for state org.apache.solr.cloud.ZkController$$Lambda$627/126518307@277d217a
[junit4] 2> 624426 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Found org.apache.solr.cloud.ZkController$$Lambda$627/126518307@277d217a directly in clusterState
[junit4] 2> 624426 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/terms/shard1
[junit4] 2> 624427 INFO (searcherExecutor-7040-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 624429 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/terms/shard1
[junit4] 2> 624429 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 624429 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 624429 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 624430 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 624432 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 624432 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 624432 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42369/qfgs/s/control_collection_shard1_replica_n1/
[junit4] 2> 624432 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 624433 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:42369/qfgs/s/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 624433 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72230145549926404-core_node2-n_0000000000
[junit4] 2> 624434 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 624434 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42369/qfgs/s/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 624435 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 624435 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "node_name":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "base_url":"http://127.0.0.1:42369/qfgs/s",
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 624436 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Add data for [control_collection] ver [1]
[junit4] 2> 624436 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [2] total [2]
[junit4] 2> 624436 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader updated per-replica states changed for: control_collection, ver: 0 , new vals: []
[junit4] 2> 624436 DEBUG (watches-7022-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader CollectionStatePredicate failed for control_collection, cversion : -1
[junit4] 2> 624436 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [2] total [2]
[junit4] 2> 624436 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader CollectionStatePredicate failed for control_collection, cversion : -1
[junit4] 2> 624436 DEBUG (watches-7022-thread-1-processing-n:127.0.0.1:42369_qfgs%2Fs x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader CollectionStatePredicate failed for control_collection, cversion : -1
[junit4] 2> 624436 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader CollectionStatePredicate failed for control_collection, cversion : -1
[junit4] 2> 624436 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader CollectionStatePredicate failed for control_collection, cversion : -1
[junit4] 2> 624536 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/control_collection/state.json version: 1
[junit4] 2> 624536 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json
[junit4] 2> 624536 INFO (zkCallback-7020-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 624537 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 624537 DEBUG (zkCallback-7020-thread-1) [ ] o.a.s.c.c.ZkStateReader Updating data for [control_collection] from [1] to [2]
[junit4] 2> 624537 DEBUG (zkCallback-7020-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [2] total [2]
[junit4] 2> 624537 DEBUG (watches-7022-thread-1) [ ] o.a.s.c.c.ZkStateReader leader found for control_collection/shard1 to be core_node2:{
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "base_url":"http://127.0.0.1:42369/qfgs/s",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}
[junit4] 2> 624537 DEBUG (watches-7022-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 624537 INFO (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 624538 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[88FD910D61D611DB]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 624538 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Refreshing lazily-loaded state for collection control_collection
[junit4] 2> 624539 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 624539 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "base_url":"http://127.0.0.1:42369/qfgs/s",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 624539 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "base_url":"http://127.0.0.1:42369/qfgs/s",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 624539 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "base_url":"http://127.0.0.1:42369/qfgs/s",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}
[junit4] 2> 624539 DEBUG (OverseerStateUpdate-72230145549926404-127.0.0.1:42369_qfgs%2Fs-n_0000000000) [n:127.0.0.1:42369_qfgs%2Fs ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(control_collection//collections/control_collection/state.json/2 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42369_qfgs%2Fs",
[junit4] 2> "base_url":"http://127.0.0.1:42369/qfgs/s",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 624540 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Add data for [control_collection] ver [2]
[junit4] 2> 624540 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [2] total [2]
[junit4] 2> 624540 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader updated per-replica states changed for: control_collection, ver: 0 , new vals: []
[junit4] 2> 624540 DEBUG (qtp1843735856-10437) [n:127.0.0.1:42369_qfgs%2Fs c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkS
[...truncated too long message...]
}{localhost:0}
[junit4] 2> 2030853 INFO (Listener at localhost.localdomain/40495) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2030853 INFO (Listener at localhost.localdomain/40495) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@552539f0{static,/static,jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.4-tests.jar!/webapps/static,STOPPED}
[junit4] 2> 2030862 INFO (Listener at localhost.localdomain/40495) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.w.WebAppContext@17cb7444{hdfs,/,null,STOPPED}{jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.4-tests.jar!/webapps/hdfs}
[junit4] 2> 2030862 INFO (Listener at localhost.localdomain/40495) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@13563ed8{HTTP/1.1, (http/1.1)}{localhost.localdomain:0}
[junit4] 2> 2030862 INFO (Listener at localhost.localdomain/40495) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2030862 INFO (Listener at localhost.localdomain/40495) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@50bb51c3{static,/static,jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.4-tests.jar!/webapps/static,STOPPED}
[junit4] 2> 2030875 INFO (Listener at localhost.localdomain/40495) [ ] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-462 after mutting 0 log messages
[junit4] 2> 2030875 INFO (Listener at localhost.localdomain/40495) [ ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-463 for ERROR logs matching regex: ignore_exception
[junit4] 2> 2030875 INFO (Listener at localhost.localdomain/40495) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Feb 21, 2024 4:57:43 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 17 leaked thread(s).
[junit4] 2> Feb 21, 2024 4:57:53 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.core.backup.repository.HdfsBackupRepositoryIntegrationTest:
[junit4] 2> 1) Thread[id=35747, name=Command processor, state=WAITING, group=TGRP-HdfsBackupRepositoryIntegrationTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.processQueue(BPServiceActor.java:1291)
[junit4] 2> at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.run(BPServiceActor.java:1275)
[junit4] 2> Feb 21, 2024 4:57:53 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=35747, name=Command processor, state=WAITING, group=TGRP-HdfsBackupRepositoryIntegrationTest]
[junit4] 2> 2040896 ERROR (Command processor) [ ] o.a.h.h.s.d.DataNode Command processor encountered interrupt and exit.
[junit4] 2> 2040896 WARN (Command processor) [ ] o.a.h.h.s.d.DataNode Ending command processor service for: Thread[Command processor,5,TGRP-HdfsBackupRepositoryIntegrationTest]
[junit4] 2> Feb 21, 2024 4:57:53 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Lucene87, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=es-US, timezone=Australia/Victoria
[junit4] 2> NOTE: Linux 4.15.0-213-generic amd64/Temurin 1.8.0_362 (64-bit)/cpus=4,threads=3,free=191568000,total=521142272
[junit4] 2> NOTE: All tests run in this JVM: [SpatialHeatmapFacetsTest, PluginInfoTest, TestRebalanceLeaders, TestSQLHandlerNonCloud, TestHdfsBackupRestoreCore, TestRawResponseWriter, BasicAuthStandaloneTest, SolrGraphiteReporterTest, SpatialFilterTest, TestLeaderElectionWithEmptyReplica, CloneFieldUpdateProcessorFactoryTest, ZkDynamicConfigTest, TestManagedSchemaAPI, VMParamsZkACLAndCredentialsProvidersTest, TestManagedSynonymFilterFactory, TestStressLiveNodes, NumberUtilsTest, BackupIdTest, TestSolrCloudWithKerberosAlt, TestCoordinatorRole, LeaderElectionTest, SolrCloudExampleTest, HttpPartitionTest, TestSmileRequest, TestAnalyzedSuggestions, TestCloudNestedDocsSort, AnalyticsMergeStrategyTest, PeerSyncWithLeaderTest, TestBackupRepositoryFactory, TestExactSharedStatsCache, IndexSchemaTest, SearchRateTriggerTest, TolerantUpdateProcessorTest, OverseerStatusTest, DistribJoinFromCollectionTest, SolrCoreMetricManagerTest, CoreAdminOperationTest, TestCollectionAPI, HealthCheckHandlerTest, MetricTriggerIntegrationTest, ExplicitHLLTest, TestTextField, TestConfig, TestWordDelimiterFilterFactory, TestBlobHandler, BlockDirectoryTest, TestCollationField, ZookeeperStatusHandlerTest, SolrLogAuditLoggerPluginTest, AdminHandlersProxyTest, SolrCloudReportersTest, TestExtendedDismaxParser, DeleteShardTest, DeleteInactiveReplicaTest, TestTestInjection, HttpTriggerListenerTest, TestMacroExpander, TestExpandComponent, SpellingQueryConverterTest, TestTolerantUpdateProcessorRandomCloud, JWTVerificationkeyResolverTest, EmbeddedSolrNoSerializeTest, ClusterStateUpdateTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestSimUtils, TestSchemaVersionResource, TestGraphMLResponseWriter, TestReplicaProperties, TestTolerantSearch, TestSafeXMLParsing, NodeLostTriggerIntegrationTest, SolrRequestParserTest, TestSolrRequestInfo, HttpSolrCallCloudTest, DirectoryFactoriesTest, BooleanFieldTest, PropertiesRequestHandlerTest, RemoteQueryErrorTest, AsyncCallRequestStatusResponseTest, TestEmbeddedSolrServerConstructors, TestRandomDVFaceting, TestSchemaDesignerSettingsDAO, TestReloadDeadlock, TestFacetMethods, CollectionsAPIAsyncDistributedZkTest, TestFiltering, ThreadDumpHandlerTest, AuditLoggerPluginTest, CustomTermsComponentTest, TestFastOutputStream, ZkNodePropsTest, TestCollapseQParserPlugin, TestApiFramework, MoreLikeThisHandlerTest, AddBlockUpdateTest, TestImplicitCoreProperties, AutoScalingHandlerTest, LukeRequestHandlerTest, DocValuesTest, MoveReplicaTest, DistanceFunctionTest, TestChildDocTransformer, TaggingAttributeTest, DistributedTermsComponentTest, TestFieldTypeResource, BJQFilterAccessibleTest, LeaderVoteWaitTimeoutTest, TermVectorComponentDistributedTest, TestCustomStream, TestSha256AuthenticationProvider, TestCursorMarkWithoutUniqueKey, TestXmlQParser, TestQuerySenderListener, VersionInfoTest, WrapperMergePolicyFactoryTest, HighlighterMaxOffsetTest, TestRTGBase, SpellCheckComponentTest, HdfsThreadLeakTest, ChaosMonkeySafeLeaderTest, CloudExitableDirectoryReaderTest, CollectionStateFormat2Test, ConcurrentCreateRoutedAliasTest, DeleteLastCustomShardedReplicaTest, DistributedQueueTest, DocValuesNotIndexedTest, ForceLeaderTest, HttpPartitionWithTlogReplicasTest, MetricsHistoryWithAuthIntegrationTest, MultiThreadedOCPTest, ReplaceNodeNoTargetTest, ReplaceNodeTest, RouteFieldTest, RoutingToNodesWithPropertiesTest, SSLMigrationTest, SaslZkACLProviderTest, ShardRoutingCustomTest, ShardTermsTest, SharedFSAutoReplicaFailoverTest, SolrXmlInZkTest, SplitShardTest, TestCloudDeleteByQuery, TestCloudPseudoReturnFields, TestCloudRecovery, TestConfigSetsAPI, TestDistribDocBasedVersion, TestDynamicFieldNamesIndexCorrectly, TestPrepRecovery, TestPullReplicaWithAuth, TestSegmentSorting, ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest, NodeLostTriggerTest, NodeMarkersRegistrationTest, ScheduledMaintenanceTriggerTest, SystemLogListenerTest, TriggerEventQueueTest, TriggerIntegrationTest, HdfsRecoveryZkTest, HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, StressHdfsTest, TestClusterStateMutator, ZkCollectionPropsCachingTest, ByteBuffersDirectoryFactoryTest, QueryResultKeyTest, TestMergePolicyConfig, TestSimpleTextCodec, TestSolrDeletionPolicy2, TestSolrXml, HdfsBackupRepositoryIntegrationTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HdfsBackupRepositoryIntegrationTest -Dtests.seed=88FD910D61D611DB -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=es-US -Dtests.timezone=Australia/Victoria -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J2 | HdfsBackupRepositoryIntegrationTest (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.core.backup.repository.HdfsBackupRepositoryIntegrationTest:
[junit4] > 1) Thread[id=35747, name=Command processor, state=WAITING, group=TGRP-HdfsBackupRepositoryIntegrationTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.processQueue(BPServiceActor.java:1291)
[junit4] > at org.apache.hadoop.hdfs.server.datanode.BPServiceActor$CommandProcessingThread.run(BPServiceActor.java:1275)
[junit4] > at __randomizedtesting.SeedInfo.seed([88FD910D61D611DB]:0)
[junit4] Completed [671/959 (2!)] on J2 in 14.13s, 10 tests, 1 error <<< FAILURES!
[...truncated 55378 lines...]