Build:
https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-NightlyTests-8.11/15/
1 tests failed.
FAILED: org.apache.solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest.test
Error Message:
There are still nodes recoverying - waited for 600 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 600
seconds
at
__randomizedtesting.SeedInfo.seed([805224A5C6481930:8061B7F68B474C8]:0)
at org.junit.Assert.fail(Assert.java:89)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:197)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:1077)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1634)
at
org.apache.solr.cloud.TlogReplayBufferedWhileIndexingTest.test(TlogReplayBufferedWhileIndexingTest.java:97)
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:1750)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:57)
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:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13687 lines...]
[junit4] Suite:
org.apache.solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest
[junit4] 2> 2626 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-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-NightlyTests-8.11/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2646 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-1 for ERROR logs
matching regex: ignore_exception
[junit4] 2> 2651 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.a.s.SolrTestCaseJ4 Created dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/data-dir-1-001
[junit4] 2> 2785 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2844 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.e.j.u.log Logging initialized @2872ms to
org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 2851 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 2883 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2887 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system
property: /fpiw/
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 6484 WARN
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 6591 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z;
git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 6633 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 6633 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 6637 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 6794 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@51b8184a{static,/static,jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.2-tests.jar!/webapps/static,AVAILABLE}
[junit4] 2> 7182 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.w.WebAppContext@30dbca40{hdfs,/,file:///home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/jetty-localhost_localdomain-40999-hadoop-hdfs-3_2_2-tests_jar-_-any-5136110938919831490/webapp/,AVAILABLE}{jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.2-tests.jar!/webapps/hdfs}
[junit4] 2> 7206 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.e.j.s.AbstractConnector Started ServerConnector@5974120c{HTTP/1.1,
(http/1.1)}{localhost.localdomain:40999}
[junit4] 2> 7206 INFO
(SUITE-HdfsTlogReplayBufferedWhileIndexingTest-seed#[805224A5C6481930]-worker)
[ ] o.e.j.s.Server Started @7238ms
[junit4] 2> 8970 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> 9058 WARN (Listener at localhost.localdomain/35033) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 9066 INFO (Listener at localhost.localdomain/35033) [ ]
o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git:
8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 9067 INFO (Listener at localhost.localdomain/35033) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 9067 INFO (Listener at localhost.localdomain/35033) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 9067 INFO (Listener at localhost.localdomain/35033) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 9068 INFO (Listener at localhost.localdomain/35033) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1da4942b{static,/static,jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.2-tests.jar!/webapps/static,AVAILABLE}
[junit4] 2> 9194 INFO (Listener at localhost.localdomain/35033) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.w.WebAppContext@30532472{datanode,/,file:///home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/jetty-localhost-44239-hadoop-hdfs-3_2_2-tests_jar-_-any-5780263607951046326/webapp/,AVAILABLE}{jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.2-tests.jar!/webapps/datanode}
[junit4] 2> 9195 INFO (Listener at localhost.localdomain/35033) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@78986182{HTTP/1.1,
(http/1.1)}{localhost:44239}
[junit4] 2> 9195 INFO (Listener at localhost.localdomain/35033) [ ]
o.e.j.s.Server Started @9228ms
[junit4] 2> 13475 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x5b8af2e98c08ed2c: Processing first storage report for
DS-2adb66ac-fb81-4eaf-b311-36ba02d652f1 from datanode
ac512017-a53a-49ff-a7ed-e14804a41bbb
[junit4] 2> 13478 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x5b8af2e98c08ed2c: from storage
DS-2adb66ac-fb81-4eaf-b311-36ba02d652f1 node
DatanodeRegistration(127.0.0.1:34263,
datanodeUuid=ac512017-a53a-49ff-a7ed-e14804a41bbb, infoPort=43723,
infoSecurePort=0, ipcPort=35725,
storageInfo=lv=-57;cid=testClusterID;nsid=85831777;c=1637148064151), blocks: 0,
hasStaleStorage: true, processing time: 3 msecs, invalidatedBlocks: 0
[junit4] 2> 13478 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x5b8af2e98c08ed2c: Processing first storage report for
DS-b15d9e81-d991-4cae-a7a8-ba8c67168cbe from datanode
ac512017-a53a-49ff-a7ed-e14804a41bbb
[junit4] 2> 13478 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x5b8af2e98c08ed2c: from storage
DS-b15d9e81-d991-4cae-a7a8-ba8c67168cbe node
DatanodeRegistration(127.0.0.1:34263,
datanodeUuid=ac512017-a53a-49ff-a7ed-e14804a41bbb, infoPort=43723,
infoSecurePort=0, ipcPort=35725,
storageInfo=lv=-57;cid=testClusterID;nsid=85831777;c=1637148064151), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs, invalidatedBlocks: 0
[junit4] 2> 13578 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-1 after mutting 0 log
messages
[junit4] 2> 13578 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-2 for ERROR logs
matching regex: ignore_exception
[junit4] 2> 13589 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 13593 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 13593 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 13937 WARN (ZkTestServer Run Thread) [ ]
o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 13997 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer start zk server on port: 43123
[junit4] 2> 13997 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:43123
[junit4] 2> 14000 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43123
[junit4] 2> 14000 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43123
[junit4] 2> 14303 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 14902 INFO (zkConnectionManagerCallback-4-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 14902 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 14948 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 14951 INFO (zkConnectionManagerCallback-6-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 14951 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 14992 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 15001 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/core/src/test-files/solr/collection1/conf/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 15010 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 15013 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 15016 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 15025 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 15040 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 15048 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 15059 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 15071 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 15083 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 15086 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless
explicitly asked otherwise
[junit4] 2> 17474 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 17474 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 17475 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z;
git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 17478 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 17478 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 17478 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 17483 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@37190aff{/fpiw,null,AVAILABLE}
[junit4] 2> 17485 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.e.j.s.AbstractConnector Started ServerConnector@20a15f1f{HTTP/1.1,
(http/1.1, h2c)}{127.0.0.1:43093}
[junit4] 2> 17485 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.e.j.s.Server Started @17517ms
[junit4] 2> 17485 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost.localdomain:35033/hdfs__localhost.localdomain_35033__home_jenkins_jenkins-slave_workspace_Lucene_Lucene-Solr-NightlyTests-8.11_checkout_solr_build_solr-core_test_J3_temp_solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001_tempDir-002_control_data,
hostContext=/fpiw, hostPort=43093,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/../../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/control-001/cores}
[junit4] 2> 17509 ERROR
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may
be missing or incomplete.
[junit4] 2> 17510 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 17510 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 8.11.0
[junit4] 2> 17510 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
null
[junit4] 2> 17510 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr
[junit4] 2> 17510 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2021-11-17T11:21:16.696Z
[junit4] 2> 17521 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 17529 INFO (zkConnectionManagerCallback-8-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 17530 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 17636 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 17637 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/../../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/control-001/solr.xml
[junit4] 2> 17747 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 17747 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 17763 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 18849 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 18916 WARN
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.e.j.u.s.S.config Trusting all certificates configured for
Client@690c0c8c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 18916 WARN
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@690c0c8c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 19108 WARN
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.e.j.u.s.S.config Trusting all certificates configured for
Client@431eaf0e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 19108 WARN
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@431eaf0e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 19309 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43123/solr
[junit4] 2> 19353 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 19369 INFO (zkConnectionManagerCallback-19-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19372 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 19389 WARN
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]-SendThread(127.0.0.1:43123))
[ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for
session 0x102872779330003.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x102872779330003, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x102872779330003, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 19514 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 19516 INFO (zkConnectionManagerCallback-21-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19516 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 19796 WARN
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.c.ZkController Contents of zookeeper
/security.json are world-readable; consider setting up ACLs as described in
https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 19848 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:43093_fpiw
[junit4] 2> 19850 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.c.Overseer Overseer
(id=72769147598209028-127.0.0.1:43093_fpiw-n_0000000000) starting
[junit4] 2> 20032 INFO
(OverseerStateUpdate-72769147598209028-127.0.0.1:43093_fpiw-n_0000000000)
[n:127.0.0.1:43093_fpiw ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:43093_fpiw
[junit4] 2> 20033 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43093_fpiw
[junit4] 2> 20047 INFO
(OverseerStateUpdate-72769147598209028-127.0.0.1:43093_fpiw-n_0000000000)
[n:127.0.0.1:43093_fpiw ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 20115 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 20135 WARN
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] 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> 20523 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 20613 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134
[junit4] 2> 20615 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134
[junit4] 2> 20615 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134
[junit4] 2> 20664 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930])
[n:127.0.0.1:43093_fpiw ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/control-001/cores
[junit4] 2> 20753 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 20757 INFO (zkConnectionManagerCallback-38-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 20757 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 20759 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 20761 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43123/solr
ready
[junit4] 2> 21192 INFO
(OverseerThreadFactory-28-thread-1-processing-n:127.0.0.1:43093_fpiw)
[n:127.0.0.1:43093_fpiw ] o.a.s.c.a.c.CreateCollectionCmd Create collection
control_collection
[junit4] 2> 21428 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core
create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 21433 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient core cache for max 4 cores with initial capacity of 4
[junit4] 2> 22767 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 22783 WARN (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use
solr.xml:/metrics/reporter section instead
[junit4] 2> 22838 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 22885 WARN (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.D.s.CurrencyField Solr loaded a deprecated plugin/analysis class
[solr.CurrencyField]. Please consult documentation how to replace it
accordingly.
[junit4] 2> 23281 WARN (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.D.s.GeoHashField Solr loaded a deprecated plugin/analysis class
[solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 23287 WARN (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.D.s.LatLonType Solr loaded a deprecated plugin/analysis class
[solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 23392 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 23948 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1'
using configuration from configset conf1, trusted=true
[junit4] 2> 23959 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.control_collection.shard1.replica_n1' (registry
'solr.core.control_collection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134
[junit4] 2> 23975 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost.localdomain:35033/solr_hdfs_home
[junit4] 2> 23976 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 23988 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore
at
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/control-001/cores/control_collection_shard1_replica_n1],
dataDir=[hdfs://localhost.localdomain:35033/solr_hdfs_home/control_collection/core_node2/data/]
[junit4] 2> 24007 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost.localdomain:35033/solr_hdfs_home/control_collection/core_node2/data/snapshot_metadata
[junit4] 2> 24023 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 24023 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 24023 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 24175 WARN (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.s.h.HdfsDirectory HDFS support in Solr has been deprecated as of 8.6. See
SOLR-14021 for details.
[junit4] 2> 24176 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 24190 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost.localdomain:35033/solr_hdfs_home/control_collection/core_node2/data
[junit4] 2> 24217 WARN (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.s.h.HdfsDirectory HDFS support in Solr has been deprecated as of 8.6. See
SOLR-14021 for details.
[junit4] 2> 24253 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost.localdomain:35033/solr_hdfs_home/control_collection/core_node2/data/index
[junit4] 2> 24260 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 24261 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 24261 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 24268 WARN (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.s.h.HdfsDirectory HDFS support in Solr has been deprecated as of 8.6. See
SOLR-14021 for details.
[junit4] 2> 24268 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 24271 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@7863aa89
[junit4] 2> 25935 WARN (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 27195 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 27195 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 27195 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 27218 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 10000ms;
[junit4] 2> 27218 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.u.CommitTracker Soft AutoCommit: if uncommitted for 3000ms;
[junit4] 2> 27225 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=29, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.19779989646384666]
[junit4] 2> 27433 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 27440 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 27472 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 27488 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1716674192132276224
[junit4] 2> 27660 INFO
(searcherExecutor-40-thread-1-processing-n:127.0.0.1:43093_fpiw
x:control_collection_shard1_replica_n1 c:control_collection s:shard1)
[n:127.0.0.1:43093_fpiw c:control_collection s:shard1
x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore
[control_collection_shard1_replica_n1] Registered new searcher autowarm time:
0 ms
[junit4] 2> 27859 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/control_collection/terms/shard1 to Terms{values={core_node2=0},
version=0}
[junit4] 2> 27864 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created
/collections/control_collection/leaders/shard1
[junit4] 2> 27889 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 27890 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 27891 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:43093/fpiw/control_collection_shard1_replica_n1/
[junit4] 2> 27896 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 27898 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.SyncStrategy
http://127.0.0.1:43093/fpiw/control_collection_shard1_replica_n1/ has no
replicas
[junit4] 2> 27902 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/control_collection/leaders/shard1/leader after winning as
/collections/control_collection/leader_elect/shard1/election/72769147598209028-core_node2-n_0000000000
[junit4] 2> 27922 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:43093/fpiw/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 27947 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw
c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 27957 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=6532
[junit4] 2> 28021 INFO (qtp1468843369-140) [n:127.0.0.1:43093_fpiw ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
45 seconds. Check all shard replicas
[junit4] 2> 28052 INFO (zkCallback-20-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 28053 INFO (zkCallback-20-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/control_collection/state.json] for collection
[control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 28065 INFO (qtp1468843369-140) [n:127.0.0.1:43093_fpiw ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:43093_fpiw&wt=javabin&version=2}
status=0 QTime=6937
[junit4] 2> 28067 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in
collection: control_collection
[junit4] 2> 28212 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 28224 INFO (zkConnectionManagerCallback-49-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 28224 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 28226 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 28228 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43123/solr
ready
[junit4] 2> 28230 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 28243 INFO
(OverseerThreadFactory-28-thread-2-processing-n:127.0.0.1:43093_fpiw)
[n:127.0.0.1:43093_fpiw ] o.a.s.c.a.c.CreateCollectionCmd Create collection
collection1
[junit4] 2> 28244 INFO
(OverseerCollectionConfigSetProcessor-72769147598209028-127.0.0.1:43093_fpiw-n_0000000000)
[n:127.0.0.1:43093_fpiw ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 28507 WARN
(OverseerThreadFactory-28-thread-2-processing-n:127.0.0.1:43093_fpiw)
[n:127.0.0.1:43093_fpiw ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to
create a collection (collection1) without cores.
[junit4] 2> 28514 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
45 seconds. Check all shard replicas
[junit4] 2> 28523 INFO (qtp1468843369-142) [n:127.0.0.1:43093_fpiw ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=2&wt=javabin&version=2}
status=0 QTime=291
[junit4] 2> 28530 INFO (watches-46-thread-1) [ ]
o.a.s.c.SolrCloudTestCase active slice count: 1 expected: 1
[junit4] 2> 28531 INFO (watches-46-thread-1) [ ]
o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 28533 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.SolrCloudTestCase active slice count: 1 expected: 1
[junit4] 2> 28533 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 28534 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.SolrCloudTestCase active slice count: 1 expected: 1
[junit4] 2> 28534 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 28535 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.SolrCloudTestCase active slice count: 1 expected: 1
[junit4] 2> 28535 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 28535 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances
pullReplicaCount=0 numOtherReplicas=2
[junit4] 2> 28656 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/shard-1-001
of type NRT for shard1
[junit4] 2> 28674 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 28674 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 28675 INFO (closeThreadPool-50-thread-1) [ ]
o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git:
8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 28697 INFO (closeThreadPool-50-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 28697 INFO (closeThreadPool-50-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 28697 INFO (closeThreadPool-50-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 28698 INFO (closeThreadPool-50-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@177bd606{/fpiw,null,AVAILABLE}
[junit4] 2> 28702 INFO (closeThreadPool-50-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@38cf4883{HTTP/1.1, (http/1.1,
h2c)}{127.0.0.1:39071}
[junit4] 2> 28906 INFO (closeThreadPool-50-thread-1) [ ]
o.e.j.s.Server Started @28734ms
[junit4] 2> 28907 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost.localdomain:35033/hdfs__localhost.localdomain_35033__home_jenkins_jenkins-slave_workspace_Lucene_Lucene-Solr-NightlyTests-8.11_checkout_solr_build_solr-core_test_J3_temp_solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001_tempDir-002_jetty1,
solrconfig=solrconfig.xml, hostContext=/fpiw, hostPort=39071,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/../../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/shard-1-001/cores}
[junit4] 2> 28907 ERROR (closeThreadPool-50-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 28907 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 28907 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.11.0
[junit4] 2> 28907 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 28907 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr
[junit4] 2> 28907 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2021-11-17T11:21:28.093Z
[junit4] 2> 28912 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 28924 INFO (zkConnectionManagerCallback-52-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 28924 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 28995 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/shard-2-001
of type NRT for shard1
[junit4] 2> 28997 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 28997 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 28998 INFO (closeThreadPool-50-thread-2) [ ]
o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git:
8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 29005 INFO (closeThreadPool-50-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 29005 INFO (closeThreadPool-50-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 29006 INFO (closeThreadPool-50-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 29008 INFO (closeThreadPool-50-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4dc179d1{/fpiw,null,AVAILABLE}
[junit4] 2> 29012 INFO (closeThreadPool-50-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@6cfdf600{HTTP/1.1, (http/1.1,
h2c)}{127.0.0.1:43161}
[junit4] 2> 29012 INFO (closeThreadPool-50-thread-2) [ ]
o.e.j.s.Server Started @29044ms
[junit4] 2> 29012 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost.localdomain:35033/hdfs__localhost.localdomain_35033__home_jenkins_jenkins-slave_workspace_Lucene_Lucene-Solr-NightlyTests-8.11_checkout_solr_build_solr-core_test_J3_temp_solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001_tempDir-002_jetty2,
solrconfig=solrconfig.xml, hostContext=/fpiw, hostPort=43161,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/../../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/shard-2-001/cores}
[junit4] 2> 29012 ERROR (closeThreadPool-50-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 29012 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 29012 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.11.0
[junit4] 2> 29012 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 29012 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr
[junit4] 2> 29012 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2021-11-17T11:21:28.198Z
[junit4] 2> 29014 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 29021 INFO (zkConnectionManagerCallback-54-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 29021 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 29025 WARN
(closeThreadPool-50-thread-2-SendThread(127.0.0.1:43123)) [ ]
o.a.z.ClientCnxn An exception was thrown while closing send thread for session
0x102872779330008.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x102872779330008, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x102872779330008, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 29034 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 29034 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/../../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/shard-1-001/solr.xml
[junit4] 2> 29042 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 29042 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 29051 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 29134 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 29136 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/../../../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/shard-2-001/solr.xml
[junit4] 2> 29141 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 29141 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 29144 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 29722 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 29726 WARN (closeThreadPool-50-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@50bff80b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 29726 WARN (closeThreadPool-50-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@50bff80b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 29738 WARN (closeThreadPool-50-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@15c58dd2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 29738 WARN (closeThreadPool-50-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@15c58dd2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 29739 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43123/solr
[junit4] 2> 29741 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 29750 INFO (zkConnectionManagerCallback-68-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 29751 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 29754 WARN
(closeThreadPool-50-thread-1-SendThread(127.0.0.1:43123)) [ ]
o.a.z.ClientCnxn An exception was thrown while closing send thread for session
0x102872779330009.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x102872779330009, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x102872779330009, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 29877 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 29889 INFO (zkConnectionManagerCallback-70-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 29889 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 29909 WARN (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.c.ZkController Contents of zookeeper
/security.json are world-readable; consider setting up ACLs as described in
https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 29926 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 29940 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.c.ZkController Publish
node=127.0.0.1:39071_fpiw as DOWN
[junit4] 2> 29941 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient core cache for max 4 cores with initial capacity of 4
[junit4] 2> 29941 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39071_fpiw
[junit4] 2> 29945 INFO (zkCallback-20-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 29953 INFO (zkCallback-69-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 29971 INFO (zkCallback-48-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 29971 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 29974 WARN (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] 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> 30088 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 30320 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134
[junit4] 2> 30325 INFO
(OverseerCollectionConfigSetProcessor-72769147598209028-127.0.0.1:43093_fpiw-n_0000000000)
[n:127.0.0.1:43093_fpiw ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 30338 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134
[junit4] 2> 30338 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134
[junit4] 2> 30340 INFO (closeThreadPool-50-thread-1)
[n:127.0.0.1:39071_fpiw ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/shard-1-001/cores
[junit4] 2> 30375 INFO (closeThreadPool-50-thread-1) [ ]
o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:39071_fpiw
[junit4] 2> 35854 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 35863 WARN (closeThreadPool-50-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@14828cc8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 35863 WARN (closeThreadPool-50-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@14828cc8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 35869 WARN (closeThreadPool-50-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@199e47be[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 35869 WARN (closeThreadPool-50-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@199e47be[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 35870 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43123/solr
[junit4] 2> 35871 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 35877 INFO (zkConnectionManagerCallback-86-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35877 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 35980 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 35985 INFO (zkConnectionManagerCallback-88-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 35985 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 35990 WARN (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.c.ZkController Contents of zookeeper
/security.json are world-readable; consider setting up ACLs as described in
https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 35990 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 35994 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.c.ZkController Publish
node=127.0.0.1:43161_fpiw as DOWN
[junit4] 2> 35996 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient core cache for max 4 cores with initial capacity of 4
[junit4] 2> 35996 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43161_fpiw
[junit4] 2> 35998 INFO (zkCallback-20-thread-3) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 36001 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 36002 WARN (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] 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> 36005 INFO (zkCallback-69-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 36005 INFO (zkCallback-48-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 36014 INFO (zkCallback-87-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 36056 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 36080 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134
[junit4] 2> 36092 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134
[junit4] 2> 36092 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134
[junit4] 2> 36096 INFO (closeThreadPool-50-thread-2)
[n:127.0.0.1:43161_fpiw ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/shard-2-001/cores
[junit4] 2> 36141 INFO (closeThreadPool-50-thread-2) [ ]
o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:43161_fpiw
[junit4] 2> 36174 INFO
(OverseerThreadFactory-28-thread-3-processing-n:127.0.0.1:43093_fpiw)
[n:127.0.0.1:43093_fpiw c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd
Node Identified 127.0.0.1:39071_fpiw for creating new replica of shard shard1
for collection collection1
[junit4] 2> 36178 INFO
(OverseerThreadFactory-28-thread-3-processing-n:127.0.0.1:43093_fpiw)
[n:127.0.0.1:43093_fpiw c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd
Returning CreateReplica command.
[junit4] 2> 36613 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create
command
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 37649 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.11.0
[junit4] 2> 37650 WARN (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig
solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter
section instead
[junit4] 2> 37659 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema
Schema name=test
[junit4] 2> 37668 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 37701 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_n1' using configuration from
configset conf1, trusted=true
[junit4] 2> 37701 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1.shard1.replica_n1' (registry
'solr.core.collection1.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@4a631134
[junit4] 2> 37702 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost.localdomain:35033/solr_hdfs_home
[junit4] 2> 37702 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 37702 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore
[[collection1_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001/shard-1-001/cores/collection1_shard1_replica_n1],
dataDir=[hdfs://localhost.localdomain:35033/solr_hdfs_home/collection1/core_node2/data/]
[junit4] 2> 37703 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost.localdomain:35033/solr_hdfs_home/collection1/core_node2/data/snapshot_metadata
[junit4] 2> 37711 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 37711 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 37711 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 37725 WARN (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.s.h.HdfsDirectory HDFS support in Solr has been deprecated as of 8.6. See
SOLR-14021 for details.
[junit4] 2> 37725 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 37727 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost.localdomain:35033/solr_hdfs_home/collection1/core_node2/data
[junit4] 2> 37738 WARN (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.s.h.HdfsDirectory HDFS support in Solr has been deprecated as of 8.6. See
SOLR-14021 for details.
[junit4] 2> 37747 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost.localdomain:35033/solr_hdfs_home/collection1/core_node2/data/index
[junit4] 2> 37754 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 37754 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 37754 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 37760 WARN (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.s.h.HdfsDirectory HDFS support in Solr has been deprecated as of 8.6. See
SOLR-14021 for details.
[junit4] 2> 37760 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 37761 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.MockRandomMergePolicy:
org.apache.lucene.index.MockRandomMergePolicy@6ea51e67
[junit4] 2> 37812 WARN (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 38113 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 38113 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 38113 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 38125 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker
Hard AutoCommit: if uncommitted for 10000ms;
[junit4] 2> 38125 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker
Soft AutoCommit: if uncommitted for 3000ms;
[junit4] 2> 38127 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=29, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.19779989646384666]
[junit4] 2> 38162 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 38163 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 38163 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 38164 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1716674203326873600
[junit4] 2> 38168 INFO
(searcherExecutor-99-thread-1-processing-n:127.0.0.1:39071_fpiw
x:collection1_shard1_replica_n1 c:collection1 s:shard1) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore
[collection1_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 38180 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms
Successful update of terms at /collections/collection1/terms/shard1 to
Terms{values={core_node2=0}, version=0}
[junit4] 2> 38180 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created
/collections/collection1/leaders/shard1
[junit4] 2> 38187 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 38187 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 38187 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:39071/fpiw/collection1_shard1_replica_n1/
[junit4] 2> 38188 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 38189 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy
http://127.0.0.1:39071/fpiw/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 38189 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/collection1/leaders/shard1/leader after winning as
/collections/collection1/leader_elect/shard1/election/72769147598209034-core_node2-n_0000000000
[junit4] 2> 38194 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:39071/fpiw/collection1_shard1_replica_n1/ shard1
[junit4] 2> 38296 INFO (zkCallback-69-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 38297 INFO (zkCallback-69-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 38298 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 38300 ERROR (qtp1022417861-208) [n:127.0.0.1:39071_fpiw
c:collection1 s:shard1 x:collection1_shard1_replica_n1 ]
o.a.s.c.c.ZkStateReader Collection collection1 is not lazy or watched!
[junit4] 2> 38305 INFO (qtp1022417861-208) [n:127.0.0.1:39071_fpiw ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1692
[junit4] 2> 38324 INFO (qtp1022417861-206) [n:127.0.0.1:39071_fpiw
c:collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/collections
params={node=127.0.0.1:39071_fpiw&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=2166
[junit4] 2> 38403 INFO (zkCallback-69-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 38403 INFO (zkCallback-69-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[j
[...truncated too long message...]
ector Stopped ServerConnector@20a15f1f{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 743035 INFO (closeThreadPool-158-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@37190aff{/fpiw,null,STOPPED}
[junit4] 2> 743035 INFO (closeThreadPool-158-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> added docs:737 with 159 fails deletes:282
[junit4] 2> added docs:719 with 188 fails deletes:293
[junit4] 2> added docs:771 with 171 fails deletes:313
[junit4] 2> 743271 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-2 after mutting 0 log
messages
[junit4] 2> 743271 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-3 for ERROR logs
matching regex: ignore_exception
[junit4] 2> 743272 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 743273 WARN
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]-SendThread(127.0.0.1:43123))
[ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for
session 0x102872779330000.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x102872779330000, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x102872779330000, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 743482 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> 13 /solr/collections/collection1/terms/shard1
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/configs/conf1
[junit4] 2> 3
/solr/collections/collection1/collectionprops.json
[junit4] 2> 3
/solr/collections/control_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 120 /solr/collections/collection1/state.json
[junit4] 2> 8 /solr/collections/control_collection/state.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 2
/solr/collections/collection1/leader_elect/shard1/election/72769147598209034-core_node2-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/72769147598209034-127.0.0.1:39071_fpiw-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 56 /solr/overseer/queue
[junit4] 2> 24 /solr/live_nodes
[junit4] 2> 15 /solr/collections/collection1/state.json
[junit4] 2> 13 /solr/overseer/collection-queue-work
[junit4] 2> 6 /solr/collections
[junit4] 2> 4 /solr/collections/control_collection/state.json
[junit4] 2>
[junit4] 2> 743492 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:43123
[junit4] 2> 743492 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43123
[junit4] 2> 743492 INFO
(TEST-HdfsTlogReplayBufferedWhileIndexingTest.test-seed#[805224A5C6481930]) [
] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43123
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=HdfsTlogReplayBufferedWhileIndexingTest -Dtests.method=test
-Dtests.seed=805224A5C6481930 -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/test-data/enwiki.random.lines.txt
-Dtests.locale=es -Dtests.timezone=America/St_Johns -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] FAILURE 730s J3 | HdfsTlogReplayBufferedWhileIndexingTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes
recoverying - waited for 600 seconds
[junit4] > at
__randomizedtesting.SeedInfo.seed([805224A5C6481930:8061B7F68B474C8]:0)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:197)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:1077)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1634)
[junit4] > at
org.apache.solr.cloud.TlogReplayBufferedWhileIndexingTest.test(TlogReplayBufferedWhileIndexingTest.java:97)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1084)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1055)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 743501 WARN (Listener at localhost.localdomain/35725) [ ]
o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 743516 INFO (Listener at localhost.localdomain/35725) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.w.WebAppContext@30532472{datanode,/,null,STOPPED}{jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.2-tests.jar!/webapps/datanode}
[junit4] 2> 743518 INFO (Listener at localhost.localdomain/35725) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@78986182{HTTP/1.1,
(http/1.1)}{localhost:0}
[junit4] 2> 743518 INFO (Listener at localhost.localdomain/35725) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 743518 INFO (Listener at localhost.localdomain/35725) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1da4942b{static,/static,jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.2-tests.jar!/webapps/static,STOPPED}
[junit4] 2> 743522 WARN (BP-2006838141-127.0.0.1-1637148064151
heartbeating to localhost.localdomain/127.0.0.1:35033) [ ]
o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager
interrupted
[junit4] 2> 743522 WARN (BP-2006838141-127.0.0.1-1637148064151
heartbeating to localhost.localdomain/127.0.0.1:35033) [ ]
o.a.h.h.s.d.DataNode Ending block pool service for: Block pool
BP-2006838141-127.0.0.1-1637148064151 (Datanode Uuid
ac512017-a53a-49ff-a7ed-e14804a41bbb) service to
localhost.localdomain/127.0.0.1:35033
[junit4] 2> 743543 INFO (Listener at localhost.localdomain/35725) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.w.WebAppContext@30dbca40{hdfs,/,null,STOPPED}{jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.2-tests.jar!/webapps/hdfs}
[junit4] 2> 743544 INFO (Listener at localhost.localdomain/35725) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@5974120c{HTTP/1.1,
(http/1.1)}{localhost.localdomain:0}
[junit4] 2> 743544 INFO (Listener at localhost.localdomain/35725) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 743545 INFO (Listener at localhost.localdomain/35725) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@51b8184a{static,/static,jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-3.2.2-tests.jar!/webapps/static,STOPPED}
[junit4] 2> 744390 INFO (Listener at localhost.localdomain/35725) [ ]
o.a.s.u.ErrorLogMuter Closing ErrorLogMuter-regex-3 after mutting 0 log messages
[junit4] 2> 744390 INFO (Listener at localhost.localdomain/35725) [ ]
o.a.s.u.ErrorLogMuter Creating ErrorLogMuter-regex-4 for ERROR logs matching
regex: ignore_exception
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.11/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest_805224A5C6481930-001
[junit4] 2> Nov 17, 2021 11:33:23 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 16 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87):
{rnd_b=PostingsFormat(name=LuceneVarGapFixedInterval),
a_t=PostingsFormat(name=LuceneFixedGap), _root_=BlockTreeOrds(blocksize=128),
id=PostingsFormat(name=LuceneVarGapFixedInterval)},
docValues:{_version_=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=694,
maxMBSortInHeap=5.138887326149612,
sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=es,
timezone=America/St_Johns
[junit4] 2> NOTE: Linux 4.15.0-147-generic amd64/Oracle Corporation
1.8.0_291 (64-bit)/cpus=4,threads=8,free=151895680,total=339214336
[junit4] 2> NOTE: All tests run in this JVM:
[HdfsTlogReplayBufferedWhileIndexingTest]
[junit4] Completed [161/953 (1!)] on J3 in 744.72s, 1 test, 1 failure <<<
FAILURES!
[...truncated 56945 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
