Build:
https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-NightlyTests-8.x/109/
1 tests failed.
FAILED:
org.apache.solr.cloud.cdcr.CdcrReplicationHandlerTest.testReplicationWithBufferedUpdates
Error Message:
Timeout while trying to assert number of documents @ source_collection
Stack Trace:
java.lang.AssertionError: Timeout while trying to assert number of documents @
source_collection
at
__randomizedtesting.SeedInfo.seed([FE2A253CBBF7DCCC:2D237522FE64405B]:0)
at
org.apache.solr.cloud.cdcr.BaseCdcrDistributedZkTest.assertNumDocs(BaseCdcrDistributedZkTest.java:278)
at
org.apache.solr.cloud.cdcr.CdcrReplicationHandlerTest.testReplicationWithBufferedUpdates(CdcrReplicationHandlerTest.java:233)
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:1090)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
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)
Caused by: java.lang.AssertionError: expected:<60> but was:<70>
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.failNotEquals(Assert.java:835)
at org.junit.Assert.assertEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:633)
at
org.apache.solr.cloud.cdcr.BaseCdcrDistributedZkTest.assertNumDocs(BaseCdcrDistributedZkTest.java:269)
... 46 more
Build Log:
[...truncated 14626 lines...]
[junit4] Suite: org.apache.solr.cloud.cdcr.CdcrReplicationHandlerTest
[junit4] 2> 2082493 INFO
(SUITE-CdcrReplicationHandlerTest-seed#[FE2A253CBBF7DCCC]-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.x/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2082493 INFO
(SUITE-CdcrReplicationHandlerTest-seed#[FE2A253CBBF7DCCC]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2082493 INFO
(SUITE-CdcrReplicationHandlerTest-seed#[FE2A253CBBF7DCCC]-worker) [ ]
o.a.s.SolrTestCaseJ4 Created dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001/data-dir-112-001
[junit4] 2> 2082493 WARN
(SUITE-CdcrReplicationHandlerTest-seed#[FE2A253CBBF7DCCC]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 2082493 INFO
(SUITE-CdcrReplicationHandlerTest-seed#[FE2A253CBBF7DCCC]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2082495 INFO
(SUITE-CdcrReplicationHandlerTest-seed#[FE2A253CBBF7DCCC]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2082496 INFO
(SUITE-CdcrReplicationHandlerTest-seed#[FE2A253CBBF7DCCC]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property:
/f_nk/fk
[junit4] 2> 2082505 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2082505 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 2082505 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2082506 WARN (ZkTestServer Run Thread) [ ]
o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 2082605 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer start zk server on port: 33523
[junit4] 2> 2082606 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:33523
[junit4] 2> 2082606 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:33523
[junit4] 2> 2082606 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 33523
[junit4] 2> 2082607 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2082612 INFO (zkConnectionManagerCallback-9825-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2082612 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2082617 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2082619 INFO (zkConnectionManagerCallback-9827-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2082619 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2082622 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-cdcr.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 2082624 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 2082628 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2082629 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 2082630 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 2082631 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 2082632 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 2082633 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2082636 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2082637 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 2082638 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer put
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 2082639 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2082640 INFO (zkConnectionManagerCallback-9831-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2082640 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2082742 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testPartialReplication
[junit4] 2> 2082867 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding
port=0)
[junit4] 2> 2082867 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number
2 ...
[junit4] 2> 2082867 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.Server jetty-9.4.34.v20201102; built:
2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm
1.8.0_252-b09
[junit4] 2> 2082867 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2082867 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2082868 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2082868 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7febdbbe{/f_nk/fk,null,AVAILABLE}
[junit4] 2> 2082869 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@1e7dab2d{SSL, (ssl,
http/1.1)}{127.0.0.1:43155}
[junit4] 2> 2082869 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.Server Started @2082897ms
[junit4] 2> 2082869 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {shards=shard1,
hostContext=/f_nk/fk, hostPort=43155,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001/jetty-001/cores}
[junit4] 2> 2082869 ERROR
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 2082869 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2082869 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 8.8.0
[junit4] 2> 2082869 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 2082869 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr
[junit4] 2> 2082869 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-12-01T14:17:30.330Z
[junit4] 2> 2082870 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2082871 INFO (zkConnectionManagerCallback-9833-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2082871 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2082976 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found
in ZooKeeper)
[junit4] 2> 2082976 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001/jetty-001/solr.xml
[junit4] 2> 2082980 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2082980 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2082981 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@16462e13, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 2083784 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2083784 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1
transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2083785 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.u.s.S.config Trusting all certificates configured for
Client@69dea6e8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2083785 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm
configured for Client@69dea6e8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2083788 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1
transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2083792 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.u.s.S.config Trusting all certificates configured for
Client@b92075d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2083792 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm
configured for Client@b92075d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2083793 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33523/solr
[junit4] 2> 2083795 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2083796 INFO (zkConnectionManagerCallback-9844-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2083796 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2083797 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC]-SendThread(127.0.0.1:33523))
[ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for
session 0x101d5954eb10004.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x101d5954eb10004, 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 0x101d5954eb10004, 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> 2083898 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.c.ConnectionManager Waiting for
client to connect to ZooKeeper
[junit4] 2> 2083900 INFO (zkConnectionManagerCallback-9846-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2083900 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.c.ConnectionManager Client is
connected to ZooKeeper
[junit4] 2> 2083970 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.OverseerElectionContext I am going
to be the leader 127.0.0.1:43155_f_nk%2Ffk
[junit4] 2> 2083970 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.Overseer Overseer
(id=72573906261704709-127.0.0.1:43155_f_nk%2Ffk-n_0000000000) starting
[junit4] 2> 2083975 INFO
(OverseerStateUpdate-72573906261704709-127.0.0.1:43155_f_nk%2Ffk-n_0000000000)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.Overseer Starting to work on the
main queue : 127.0.0.1:43155_f_nk%2Ffk
[junit4] 2> 2083977 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.ZkController Register node as live
in ZooKeeper:/live_nodes/127.0.0.1:43155_f_nk%2Ffk
[junit4] 2> 2083980 INFO (zkCallback-9845-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2083982 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.p.PackageLoader /packages.json
updated to version -1
[junit4] 2> 2083983 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] 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> 2084007 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2084028 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.m.r.SolrJmxReporter JMX monitoring
for 'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16462e13
[junit4] 2> 2084037 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.m.r.SolrJmxReporter JMX monitoring
for 'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16462e13
[junit4] 2> 2084038 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.m.r.SolrJmxReporter JMX monitoring
for 'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16462e13
[junit4] 2> 2084039 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001/jetty-001/cores
[junit4] 2> 2084163 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding
port=0)
[junit4] 2> 2084163 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number
2 ...
[junit4] 2> 2084163 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.Server jetty-9.4.34.v20201102; built:
2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm
1.8.0_252-b09
[junit4] 2> 2084164 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2084164 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2084164 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2084165 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7a723e7{/f_nk/fk,null,AVAILABLE}
[junit4] 2> 2084166 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@1bc8741f{SSL, (ssl,
http/1.1)}{127.0.0.1:39483}
[junit4] 2> 2084166 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.Server Started @2084195ms
[junit4] 2> 2084166 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {shards=shard2,
hostContext=/f_nk/fk, hostPort=39483,
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001/jetty-002/cores}
[junit4] 2> 2084166 ERROR
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 2084166 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2084167 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 8.8.0
[junit4] 2> 2084167 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 2084167 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr
[junit4] 2> 2084167 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-12-01T14:17:31.628Z
[junit4] 2> 2084171 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2084175 INFO (zkConnectionManagerCallback-9860-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2084175 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2084276 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found
in ZooKeeper)
[junit4] 2> 2084277 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001/jetty-002/solr.xml
[junit4] 2> 2084280 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2084280 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2084282 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@16462e13, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 2085015 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2085016 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1
transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2085017 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.u.s.S.config Trusting all certificates configured for
Client@3c734c20[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2085017 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm
configured for Client@3c734c20[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2085020 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1
transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2085020 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.u.s.S.config Trusting all certificates configured for
Client@37c0c61f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2085020 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm
configured for Client@37c0c61f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2085022 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33523/solr
[junit4] 2> 2085023 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2085024 INFO (zkConnectionManagerCallback-9871-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2085024 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2085126 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.c.ConnectionManager Waiting for
client to connect to ZooKeeper
[junit4] 2> 2085127 INFO (zkConnectionManagerCallback-9873-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2085127 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.c.ConnectionManager Client is
connected to ZooKeeper
[junit4] 2> 2085133 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (1)
[junit4] 2> 2085136 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.ZkController Publish
node=127.0.0.1:39483_f_nk%2Ffk as DOWN
[junit4] 2> 2085137 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient cache for 4 transient cores
[junit4] 2> 2085137 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.ZkController Register node as live
in ZooKeeper:/live_nodes/127.0.0.1:39483_f_nk%2Ffk
[junit4] 2> 2085138 INFO (zkCallback-9845-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2085139 INFO (zkCallback-9872-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2085140 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.p.PackageLoader /packages.json
updated to version -1
[junit4] 2> 2085140 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] 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> 2085153 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 2085176 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.m.r.SolrJmxReporter JMX monitoring
for 'solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16462e13
[junit4] 2> 2085188 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.m.r.SolrJmxReporter JMX monitoring
for 'solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16462e13
[junit4] 2> 2085188 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.m.r.SolrJmxReporter JMX monitoring
for 'solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16462e13
[junit4] 2> 2085190 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001/jetty-002/cores
[junit4] 2> 2085206 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2085207 INFO (zkConnectionManagerCallback-9886-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2085207 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2085208 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2085209 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:33523/solr ready
[junit4] 2> 2085252 INFO
(OverseerThreadFactory-9853-thread-1-processing-n:127.0.0.1:43155_f_nk%2Ffk)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.a.c.CreateCollectionCmd Create
collection tmp_collection
[junit4] 2> 2085358 WARN
(OverseerThreadFactory-9853-thread-1-processing-n:127.0.0.1:43155_f_nk%2Ffk)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.a.c.CreateCollectionCmd It is
unusual to create a collection (tmp_collection) without cores.
[junit4] 2> 2085363 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.h.a.CollectionsHandler Wait for new
collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2085365 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/collections
params={collection.configName=conf1&name=tmp_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&wt=javabin&version=2}
status=0 QTime=116
[junit4] 2> 2085370 INFO
(OverseerCollectionConfigSetProcessor-72573906261704709-127.0.0.1:43155_f_nk%2Ffk-n_0000000000)
[n:127.0.0.1:43155_f_nk%2Ffk ] 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> 2085371 INFO
(OverseerThreadFactory-9853-thread-2-processing-n:127.0.0.1:43155_f_nk%2Ffk)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1 ]
o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:43155_f_nk%2Ffk for
creating new replica of shard shard1 for collection tmp_collection
[junit4] 2> 2085373 INFO
(OverseerThreadFactory-9853-thread-2-processing-n:127.0.0.1:43155_f_nk%2Ffk)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1 ]
o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2085416 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk x:tmp_collection_shard1_replica_n1 ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=tmp_collection_shard1_replica_n1&action=CREATE&collection=tmp_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2085417 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk x:tmp_collection_shard1_replica_n1 ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 2086431 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 8.8.0
[junit4] 2> 2086442 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 2086517 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.6
with uniqueid field id
[junit4] 2> 2086532 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore
'tmp_collection_shard1_replica_n1' using configuration from configset conf1,
trusted=true
[junit4] 2> 2086532 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring
for 'solr.core.tmp_collection.shard1.replica_n1' (registry
'solr.core.tmp_collection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16462e13
[junit4] 2> 2086532 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SolrCore
[[tmp_collection_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001/jetty-001/cores/tmp_collection_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001/jetty-001/cores/tmp_collection_shard1_replica_n1/data/]
[junit4] 2> 2086535 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=4, maxMergeAtOnceExplicit=5,
maxMergedSegmentMB=1.8125, floorSegmentMB=1.22265625,
forceMergeDeletesPctAllowed=8.141720544216952, segmentsPerTier=36.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0,
deletesPctAllowed=29.901897693651314
[junit4] 2> 2086620 WARN (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.D.s.CdcrRequestHandler Solr loaded a
deprecated plugin/analysis class [solr.CdcrRequestHandler]. Please consult
documentation how to replace it accordingly.
[junit4] 2> 2086621 WARN (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.h.CdcrRequestHandler CDCR (in its
current form) is deprecated as of 8.6 and shall be removed in 9.0. See
SOLR-14022 for details.
[junit4] 2> 2086622 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 2086622 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 2086623 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 2086623 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 2086624 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy:
[LogDocMergePolicy: minMergeSize=1000, mergeFactor=9,
maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.42834993346185957]
[junit4] 2> 2086625 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2086626 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null
at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2086626 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000 ms
[junit4] 2> 2086632 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.h.CdcrBufferStateManager Created
znode /collections/tmp_collection/cdcr/state/buffer
[junit4] 2> 2086637 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.h.CdcrProcessStateManager Created
znode /collections/tmp_collection/cdcr/state/process
[junit4] 2> 2086655 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1684885737691938816
[junit4] 2> 2086657 INFO
(searcherExecutor-9888-thread-1-processing-n:127.0.0.1:43155_f_nk%2Ffk
x:tmp_collection_shard1_replica_n1 c:tmp_collection s:shard1)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SolrCore
[tmp_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2086660 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of
terms at /collections/tmp_collection/terms/shard1 to
Terms{values={core_node2=0}, version=0}
[junit4] 2> 2086660 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase
make sure parent is created /collections/tmp_collection/leaders/shard1
[junit4] 2> 2086665 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 2086665 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may
be the new leader - try and sync
[junit4] 2> 2086665 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:43155/f_nk/fk/tmp_collection_shard1_replica_n1/
[junit4] 2> 2086666 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 2086666 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy
https://127.0.0.1:43155/f_nk/fk/tmp_collection_shard1_replica_n1/ has no
replicas
[junit4] 2> 2086666 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase
Creating leader registration node
/collections/tmp_collection/leaders/shard1/leader after winning as
/collections/tmp_collection/leader_elect/shard1/election/72573906261704709-core_node2-n_0000000000
[junit4] 2> 2086667 INFO (zkCallback-9845-thread-1) [ ]
o.a.s.h.CdcrLeaderStateManager Received new leader state @ tmp_collection:shard1
[junit4] 2> 2086676 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am
the new leader:
https://127.0.0.1:43155/f_nk/fk/tmp_collection_shard1_replica_n1/ shard1
[junit4] 2> 2086779 INFO (zkCallback-9845-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2086779 INFO (zkCallback-9845-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2086780 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 2086783 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=tmp_collection_shard1_replica_n1&action=CREATE&collection=tmp_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1367
[junit4] 2> 2086787 INFO (qtp1767064847-23561)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection ] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/collections
params={node=127.0.0.1:43155_f_nk%252Ffk&action=ADDREPLICA&collection=tmp_collection&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=1419
[junit4] 2> 2086790 INFO
(OverseerCollectionConfigSetProcessor-72573906261704709-127.0.0.1:43155_f_nk%2Ffk-n_0000000000)
[n:127.0.0.1:43155_f_nk%2Ffk ] 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> 2086790 INFO
(OverseerThreadFactory-9853-thread-3-processing-n:127.0.0.1:43155_f_nk%2Ffk)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1 ]
o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:39483_f_nk%2Ffk for
creating new replica of shard shard1 for collection tmp_collection
[junit4] 2> 2086791 INFO
(OverseerThreadFactory-9853-thread-3-processing-n:127.0.0.1:43155_f_nk%2Ffk)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1 ]
o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2086832 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk x:tmp_collection_shard1_replica_n3 ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&name=tmp_collection_shard1_replica_n3&action=CREATE&collection=tmp_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2086938 INFO (zkCallback-9845-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2086938 INFO (zkCallback-9845-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2086938 INFO (zkCallback-9845-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2087845 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 8.8.0
[junit4] 2> 2087868 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 2087928 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.s.IndexSchema Loaded schema test/1.6
with uniqueid field id
[junit4] 2> 2087944 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore
'tmp_collection_shard1_replica_n3' using configuration from configset conf1,
trusted=true
[junit4] 2> 2087945 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring
for 'solr.core.tmp_collection.shard1.replica_n3' (registry
'solr.core.tmp_collection.shard1.replica_n3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@16462e13
[junit4] 2> 2087945 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.SolrCore
[[tmp_collection_shard1_replica_n3] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001/jetty-002/cores/tmp_collection_shard1_replica_n3],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001/jetty-002/cores/tmp_collection_shard1_replica_n3/data/]
[junit4] 2> 2087949 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=4, maxMergeAtOnceExplicit=5,
maxMergedSegmentMB=1.8125, floorSegmentMB=1.22265625,
forceMergeDeletesPctAllowed=8.141720544216952, segmentsPerTier=36.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0,
deletesPctAllowed=29.901897693651314
[junit4] 2> 2088025 WARN (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.h.CdcrRequestHandler CDCR (in its
current form) is deprecated as of 8.6 and shall be removed in 9.0. See
SOLR-14022 for details.
[junit4] 2> 2088025 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 2088026 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 2088027 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit:
disabled
[junit4] 2> 2088027 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit:
disabled
[junit4] 2> 2088027 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy:
[LogDocMergePolicy: minMergeSize=1000, mergeFactor=9,
maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.42834993346185957]
[junit4] 2> 2088029 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2088029 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null
at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2088029 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000 ms
[junit4] 2> 2088031 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1684885739134779392
[junit4] 2> 2088033 INFO
(searcherExecutor-9895-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.SolrCore
[tmp_collection_shard1_replica_n3] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2088037 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of
terms at /collections/tmp_collection/terms/shard1 to
Terms{values={core_node2=0, core_node4=0}, version=1}
[junit4] 2> 2088037 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase
make sure parent is created /collections/tmp_collection/leaders/shard1
[junit4] 2> 2088038 INFO (qtp1767064847-23565)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node2
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.S.Request
[tmp_collection_shard1_replica_n1] webapp=/f_nk/fk path=/cdcr
params={action=LASTPROCESSEDVERSION&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 2088041 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.ZkController Core needs to
recover:tmp_collection_shard1_replica_n3
[junit4] 2> 2088041 INFO
(updateExecutor-9867-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.DefaultSolrCoreState Running
recovery
[junit4] 2> 2088041 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Starting recovery
process. recoveringAfterStartup=true
[junit4] 2> 2088045 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy startupVersions
is empty
[junit4] 2> 2088045 INFO (qtp713189690-23600)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&name=tmp_collection_shard1_replica_n3&action=CREATE&collection=tmp_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1213
[junit4] 2> 2088055 INFO (qtp1767064847-23561)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node2
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.S.Request
[tmp_collection_shard1_replica_n1] webapp=/f_nk/fk path=/admin/ping
params={wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 2088055 INFO (qtp1767064847-23561)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node2
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.S.Request
[tmp_collection_shard1_replica_n1] webapp=/f_nk/fk path=/admin/ping
params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2088056 INFO (qtp1767064847-23562)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection ] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/collections
params={node=127.0.0.1:39483_f_nk%252Ffk&action=ADDREPLICA&collection=tmp_collection&shard=shard1&type=NRT&wt=javabin&version=2}
status=0 QTime=1267
[junit4] 2> 2088056 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Begin buffering
updates. core=[tmp_collection_shard1_replica_n3]
[junit4] 2> 2088056 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.UpdateLog Starting to buffer
updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 2088056 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Publishing state
of core [tmp_collection_shard1_replica_n3] as recovering, leader is
[https://127.0.0.1:43155/f_nk/fk/tmp_collection_shard1_replica_n1/] and I am
[https://127.0.0.1:39483/f_nk/fk/tmp_collection_shard1_replica_n3/]
[junit4] 2> 2088059 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Sending prep
recovery command to [https://127.0.0.1:43155/f_nk/fk]; [WaitForState:
action=PREPRECOVERY&core=tmp_collection_shard1_replica_n1&nodeName=127.0.0.1:39483_f_nk%252Ffk&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 2088063 INFO (qtp1767064847-23564)
[n:127.0.0.1:43155_f_nk%2Ffk x:tmp_collection_shard1_replica_n1 ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node4, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 2088063 INFO (qtp1767064847-23564)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.h.a.PrepRecoveryOp In
WaitForState(recovering): collection=tmp_collection, shard=shard1,
thisCore=tmp_collection_shard1_replica_n1, leaderDoesNotNeedRecovery=false,
isLeader? true, live=true, checkLive=true, currentState=down,
localState=active, nodeName=127.0.0.1:39483_f_nk%2Ffk, coreNodeName=core_node4,
onlyIfActiveCheckResult=false, nodeProps: core_node4:{
[junit4] 2> "core":"tmp_collection_shard1_replica_n3",
[junit4] 2> "base_url":"https://127.0.0.1:39483/f_nk/fk";,
[junit4] 2> "node_name":"127.0.0.1:39483_f_nk%2Ffk",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}
[junit4] 2> 2088063 INFO (qtp1767064847-23564)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.h.a.PrepRecoveryOp In
WaitForState(recovering): collection=tmp_collection, shard=shard1,
thisCore=tmp_collection_shard1_replica_n1, leaderDoesNotNeedRecovery=false,
isLeader? true, live=true, checkLive=true, currentState=down,
localState=active, nodeName=127.0.0.1:39483_f_nk%2Ffk, coreNodeName=core_node4,
onlyIfActiveCheckResult=false, nodeProps: core_node4:{
[junit4] 2> "core":"tmp_collection_shard1_replica_n3",
[junit4] 2> "base_url":"https://127.0.0.1:39483/f_nk/fk";,
[junit4] 2> "node_name":"127.0.0.1:39483_f_nk%2Ffk",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}
[junit4] 2> 2088063 INFO (qtp1767064847-23564)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.h.a.PrepRecoveryOp In
WaitForState(recovering): collection=tmp_collection, shard=shard1,
thisCore=tmp_collection_shard1_replica_n1, leaderDoesNotNeedRecovery=false,
isLeader? true, live=true, checkLive=true, currentState=down,
localState=active, nodeName=127.0.0.1:39483_f_nk%2Ffk, coreNodeName=core_node4,
onlyIfActiveCheckResult=false, nodeProps: core_node4:{
[junit4] 2> "core":"tmp_collection_shard1_replica_n3",
[junit4] 2> "base_url":"https://127.0.0.1:39483/f_nk/fk";,
[junit4] 2> "node_name":"127.0.0.1:39483_f_nk%2Ffk",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}
[junit4] 2> 2088157 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish -
collection: tmp_collection failOnTimeout: true timeout (sec):
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:down live:true
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:down live:true
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:down live:true
[junit4] 2> 2088158 INFO (zkCallback-9845-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088158 INFO (zkCallback-9872-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088158 INFO (zkCallback-9845-thread-4) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088158 INFO (zkCallback-9845-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088158 INFO (zkCallback-9872-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088159 INFO (zkCallback-9872-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088159 INFO (watches-9847-thread-1)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.h.a.PrepRecoveryOp In
WaitForState(recovering): collection=tmp_collection, shard=shard1,
thisCore=tmp_collection_shard1_replica_n1, leaderDoesNotNeedRecovery=false,
isLeader? true, live=true, checkLive=true, currentState=recovering,
localState=active, nodeName=127.0.0.1:39483_f_nk%2Ffk, coreNodeName=core_node4,
onlyIfActiveCheckResult=false, nodeProps: core_node4:{
[junit4] 2> "core":"tmp_collection_shard1_replica_n3",
[junit4] 2> "base_url":"https://127.0.0.1:39483/f_nk/fk";,
[junit4] 2> "node_name":"127.0.0.1:39483_f_nk%2Ffk",
[junit4] 2> "state":"recovering",
[junit4] 2> "type":"NRT"}
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:recovering live:true
[junit4] 2> 2088159 INFO (qtp1767064847-23564)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/cores
params={nodeName=127.0.0.1:39483_f_nk%252Ffk&onlyIfLeaderActive=true&core=tmp_collection_shard1_replica_n1&coreNodeName=core_node4&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=96
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:recovering live:true
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:recovering live:true
[junit4] 2> 2088660 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Attempting to
PeerSync from
[https://127.0.0.1:43155/f_nk/fk/tmp_collection_shard1_replica_n1/] -
recoveringAfterStartup=[true]
[junit4] 2> 2088660 WARN
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.PeerSyncWithLeader no frame of
reference to tell if we've missed updates
[junit4] 2> 2088660 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy PeerSync Recovery
was not successful - trying replication.
[junit4] 2> 2088660 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Starting
Replication Recovery.
[junit4] 2> 2088660 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Attempting to
replicate from
[https://127.0.0.1:43155/f_nk/fk/tmp_collection_shard1_replica_n1/].
[junit4] 2> 2088710 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.p.DistributedUpdateProcessor
Ignoring commit while not ACTIVE - state: BUFFERING replay: false
[junit4] 2> 2088710 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.S.Request
[tmp_collection_shard1_replica_n3] webapp=/f_nk/fk path=/update
params={update.distrib=FROMLEADER&update.chain=cdcr-processor-chain&waitSearcher=true&openSearcher=false&commit=true&softCommit=false&distrib.from=https://127.0.0.1:43155/f_nk/fk/tmp_collection_shard1_replica_n1/&commit_end_point=replicas&wt=javabin&version=2&expungeDeletes=false}
status=0 QTime=0
[junit4] 2> 2088713 INFO (qtp1767064847-23561)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node2
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.S.Request
[tmp_collection_shard1_replica_n1] webapp=/f_nk/fk path=/update
params={waitSearcher=true&openSearcher=false&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=52
[junit4] 2> 2088720 INFO (qtp1767064847-23562)
[n:127.0.0.1:43155_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node2
x:tmp_collection_shard1_replica_n1 ] o.a.s.c.S.Request
[tmp_collection_shard1_replica_n1] webapp=/f_nk/fk path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 2088720 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.h.IndexFetcher Leader's generation: 1
[junit4] 2> 2088721 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.h.IndexFetcher Leader's version: 0
[junit4] 2> 2088721 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.h.IndexFetcher Follower's
generation: 1
[junit4] 2> 2088721 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.h.IndexFetcher Follower's version: 0
[junit4] 2> 2088721 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.h.IndexFetcher New index in Leader.
Deleting mine...
[junit4] 2> 2088726 INFO
(searcherExecutor-9895-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.SolrCore
[tmp_collection_shard1_replica_n3] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2088726 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 2088726 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Replication
Recovery was successful.
[junit4] 2> 2088726 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Registering as
Active after recovery.
[junit4] 2> 2088727 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Updating version
bucket highest from index after successful recovery.
[junit4] 2> 2088727 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1684885739864588288
[junit4] 2> 2088728 INFO
(recoveryExecutor-9869-thread-1-processing-n:127.0.0.1:39483_f_nk%2Ffk
x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Finished recovery
process, successful=[true]
[junit4] 2> 2088792 INFO
(OverseerCollectionConfigSetProcessor-72573906261704709-127.0.0.1:43155_f_nk%2Ffk-n_0000000000)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 2088830 INFO (zkCallback-9845-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088830 INFO (zkCallback-9872-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088830 INFO (zkCallback-9872-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088830 INFO (zkCallback-9845-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088830 INFO (zkCallback-9872-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088830 INFO (zkCallback-9845-thread-4) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:active live:true
[junit4] 1> no one is recoverying
[junit4] 2> 2088831 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection:
tmp_collection
[junit4] 2> 2088835 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2088839 INFO (zkConnectionManagerCallback-9906-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2088839 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2088840 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2088842 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplication-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:33523/solr ready
[junit4] 2> 2088847 INFO
(OverseerThreadFactory-9853-thread-4-processing-n:127.0.0.1:43155_f_nk%2Ffk)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Executing Collection
Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true,
asyncId=null
[junit4] 2> 2088849 INFO (qtp1767064847-23563)
[n:127.0.0.1:43155_f_nk%2Ffk x:tmp_collection_shard1_replica_n1 ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.tmp_collection.shard1.replica_n1 tag=null
[junit4] 2> 2088849 INFO (qtp1767064847-23563)
[n:127.0.0.1:43155_f_nk%2Ffk x:tmp_collection_shard1_replica_n1 ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5d4fa0da: rootName = null,
domain = solr.core.tmp_collection.shard1.replica_n1, service url = null, agent
id = null] for registry
solr.core.tmp_collection.shard1.replica_n1/com.codahale.metrics.MetricRegistry@2e4ec525
[junit4] 2> 2088878 INFO (qtp1767064847-23563)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.SolrCore
[tmp_collection_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@7708df52
[junit4] 2> 2088878 INFO (qtp1767064847-23563)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.tmp_collection.shard1.replica_n1
tag=SolrCore@7708df52
[junit4] 2> 2088878 INFO (qtp1767064847-23563)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.tmp_collection.shard1.leader
tag=SolrCore@7708df52
[junit4] 2> 2088878 INFO (qtp1767064847-23563)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.h.CdcrRequestHandler Solr core is
being closed - shutting down CDCR handler @ tmp_collection:shard1
[junit4] 2> 2088879 INFO (qtp1767064847-23563)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.u.DirectUpdateHandler2 Committing on
IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2088881 INFO (qtp1767064847-23563)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.c.ZkShardTerms Successful update of
terms at /collections/tmp_collection/terms/shard1 to
Terms{values={core_node4=0}, version=2}
[junit4] 2> 2088883 INFO (zkCallback-9872-thread-1) [ ]
o.a.s.h.CdcrLeaderStateManager Received new leader state @ tmp_collection:shard1
[junit4] 2> 2088883 INFO (qtp1767064847-23563)
[n:127.0.0.1:43155_f_nk%2Ffk ] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/cores
params={deleteInstanceDir=true&deleteMetricsHistory=true&core=tmp_collection_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=34
[junit4] 2> 2088884 INFO (zkCallback-9872-thread-2)
[n:127.0.0.1:39483_f_nk%2Ffk c:tmp_collection s:shard1 r:core_node4
x:tmp_collection_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I may
be the new leader - try and sync
[junit4] 2> 2088886 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk x:tmp_collection_shard1_replica_n3 ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.tmp_collection.shard1.replica_n3 tag=null
[junit4] 2> 2088886 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk x:tmp_collection_shard1_replica_n3 ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2808d3d6: rootName = null,
domain = solr.core.tmp_collection.shard1.replica_n3, service url = null, agent
id = null] for registry
solr.core.tmp_collection.shard1.replica_n3/com.codahale.metrics.MetricRegistry@17cd1725
[junit4] 2> 2088905 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2088986 INFO (zkCallback-9845-thread-4) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088986 INFO (zkCallback-9845-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088986 INFO (zkCallback-9845-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088986 INFO (zkCallback-9872-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088986 INFO (zkCallback-9872-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2088986 INFO (zkCallback-9872-thread-4) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/tmp_collection/state.json] for collection [tmp_collection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2089005 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2089105 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2089205 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2089305 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2089406 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2089506 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2089606 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2089706 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2089807 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2089907 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2090007 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2090107 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2090207 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2090307 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[junit4] 2> 2090408 INFO (qtp713189690-23599)
[n:127.0.0.1:39483_f_nk%2Ffk ] o.a.s.c.SolrCore Core
tmp_collection_shard1_replica_n3 is not yet closed, waiting 100 ms before
checking again.
[...truncated too long message...]
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 2226653 INFO (closeThreadPool-11121-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72573913952485384-127.0.0.1:46493_f_nk%2Ffk-n_0000000001) closing
[junit4] 2> 2226751 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.Overseer Overseer
(id=72573913952485384-127.0.0.1:46493_f_nk%2Ffk-n_0000000001) closing
[junit4] 2> 2226754 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.AbstractConnector Stopped ServerConnector@69c2c9d7{SSL, (ssl,
http/1.1)}{127.0.0.1:0}
[junit4] 2> 2226754 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@a6d012b{/f_nk/fk,null,STOPPED}
[junit4] 2> 2226754 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[FE2A253CBBF7DCCC])
[ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2226755 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testPartialReplicationWithTruncatedTlog
[junit4] 2> 2226755 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 2226756 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[FE2A253CBBF7DCCC]-SendThread(127.0.0.1:38905))
[ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for
session 0x101d59719190000.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x101d59719190000, 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 0x101d59719190000, 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> 2226858 WARN
(TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[FE2A253CBBF7DCCC]-SendThread(127.0.0.1:38905))
[ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for
session 0x101d59719190001.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x101d59719190001, 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 0x101d59719190001, 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> 2226961 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> 23 /solr/aliases.json
[junit4] 2> 13 /solr/collections/source_collection/terms/shard1
[junit4] 2> 8 /solr/collections/tmp_collection/terms/shard1
[junit4] 2> 7 /solr/collections/tmp_collection/state.json
[junit4] 2> 6
/solr/collections/source_collection/leaders/shard1/leader
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2> 4
/solr/collections/tmp_collection/leaders/shard1/leader
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 49 /solr/collections/source_collection/state.json
[junit4] 2> 27 /solr/collections/tmp_collection/state.json
[junit4] 2> 23 /solr/clusterprops.json
[junit4] 2> 23 /solr/clusterstate.json
[junit4] 2> 4
/solr/collections/source_collection/cdcr/state/buffer
[junit4] 2> 4
/solr/collections/source_collection/cdcr/state/process
[junit4] 2> 2
/solr/collections/tmp_collection/cdcr/state/buffer
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2> 2
/solr/collections/source_collection/leader_elect/shard1/election/72573913952485384-core_node3-n_0000000000
[junit4] 2> 2
/solr/collections/tmp_collection/cdcr/state/process
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 46 /solr/overseer/queue
[junit4] 2> 32 /solr/collections
[junit4] 2> 31 /solr/live_nodes
[junit4] 2> 21 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 2226966 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:38905
[junit4] 2> 2226966 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38905
[junit4] 2> 2226967 INFO
(TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[FE2A253CBBF7DCCC])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38905
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_FE2A253CBBF7DCCC-001
[junit4] 2> Dec 01, 2020 2:19:54 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87):
{_root_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)),
id=Lucene84}, docValues:{_version_=DocValuesFormat(name=Direct)},
maxPointsInLeafNode=988, maxMBSortInHeap=5.630137051555703,
sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=pt,
timezone=Asia/Bahrain
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation
1.8.0_252 (64-bit)/cpus=4,threads=1,free=110125096,total=527958016
[junit4] 2> NOTE: All tests run in this JVM:
[AtomicUpdateProcessorFactoryTest, TestReplicationHandlerDiskOverFlow,
ExternalFileFieldSortTest, LoggingHandlerTest,
TestDistributedStatsComponentCardinality, ConcurrentCreateRoutedAliasTest,
DaemonStreamApiTest, RemoteQueryErrorTest,
TestPerFieldSimilarityWithDefaultOverride, CheckHdfsIndexTest, DeleteShardTest,
ZkCollectionPropsCachingTest, ExecutePlanActionTest, EchoParamsTest,
TestFieldResource, SolrInfoBeanTest, TestSolrIndexConfig, FacetPivotSmallTest,
ChaosMonkeyNothingIsSafeWithPullReplicasTest,
TestLegacyNumericRangeQueryBuilder, CacheHeaderTest,
PreAnalyzedFieldManagedSchemaCloudTest, TestRandomFlRTGCloud,
TestDocumentBuilder, RequiredFieldsTest, TestQuerySenderListener,
TestTlogReplica, TestCloudJSONFacetJoinDomain, TestInPlaceUpdatesDistrib,
TestConfigSetsAPIExclusivity, TestComponentsName, TestSmileRequest,
SharedFSAutoReplicaFailoverTest, MoreLikeThisHandlerTest, TestLegacyFieldReuse,
TestPayloadScoreQParserPlugin, TestTrackingShardHandlerFactory,
TestTermsQParserPlugin, HLLSerializationTest,
TestLMJelinekMercerSimilarityFactory, TestSchemaResource,
BlockJoinFacetDistribTest, RegexBytesRefFilterTest, BaseCdcrDistributedZkTest,
TestDistribIDF, JvmMetricsTest, SpatialRPTFieldTypeTest,
TestCursorMarkWithoutUniqueKey, ConvertedLegacyTest, RestartWhileUpdatingTest,
ResponseHeaderTest, TestLeaderElectionZkExpiry, NodeMutatorTest,
DistributedFacetPivotWhiteBoxTest, JSONWriterTest, TestSSLRandomization,
TriggerSetPropertiesIntegrationTest, DocValuesMissingTest, TestIndexSearcher,
DistributedFacetPivotLargeTest, CdcrUpdateLogTest, AuditLoggerIntegrationTest,
TestUseDocValuesAsStored2, SimpleMLTQParserTest, TestMultiWordSynonyms,
ExitableDirectoryReaderTest, UpdateParamsTest, TestPolicyCloud,
TestManagedResource, PrimUtilsTest, SpellCheckCollatorWithCollapseTest,
RAMDirectoryFactoryTest, IndexSizeTriggerSizeEstimationTest,
TestDynamicFieldNamesIndexCorrectly, TestTolerantSearch, SuggestComponentTest,
TestSolrCoreParser, TestJmxIntegration, PeerSyncReplicationTest, TestRTGBase,
MetricsHistoryHandlerTest, SuggesterWFSTTest, TestJsonRangeFacets,
TestSchemalessBufferedUpdates, TestCircuitBreaker, TestObjectReleaseTracker,
RankQParserPluginTest, BigEndianAscendingWordSerializerTest,
ShowFileRequestHandlerTest, TestExtendedDismaxParser,
TestDelegationWithHadoopAuth, MetricUtilsTest,
TestStressCloudBlindAtomicUpdates, CurrencyRangeFacetCloudTest,
MoveReplicaTest, TestSystemCollAutoCreate, TestExplainDocTransformer,
ClassificationUpdateProcessorIntegrationTest, TestNumericRangeQuery32,
DynamicMapsTest, DirectSolrSpellCheckerTest, TestCoreBackup,
TriLevelCompositeIdRoutingTest, WordBreakSolrSpellCheckerTest,
BadComponentTest, TestHdfsCloudBackupRestore, BlobRepositoryCloudTest,
CdcrReplicationHandlerTest]
[junit4] Completed [480/921 (1!)] on J2 in 144.93s, 5 tests, 1 failure <<<
FAILURES!
[...truncated 52275 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
