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...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
