Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.6/30/
2 tests failed.
FAILED:
org.apache.solr.cloud.CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap
Error Message:
Document mismatch on target after sync expected:<10000> but was:<0>
Stack Trace:
java.lang.AssertionError: Document mismatch on target after sync
expected:<10000> but was:<0>
at
__randomizedtesting.SeedInfo.seed([8F84846AADE38943:5853AB1D19BC1104]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at
org.apache.solr.cloud.CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap(CdcrBootstrapTest.java:134)
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:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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
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:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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
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)
FAILED: org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.test
Error Message:
expected:<2> but was:<3>
Stack Trace:
java.lang.AssertionError: expected:<2> but was:<3>
at
__randomizedtesting.SeedInfo.seed([8F84846AADE38943:7D0BBB0031FE4BB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.assertSliceAndReplicaCount(SharedFSAutoReplicaFailoverTest.java:419)
at
org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.testBasics(SharedFSAutoReplicaFailoverTest.java:264)
at
org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.test(SharedFSAutoReplicaFailoverTest.java:136)
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:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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
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:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 11836 lines...]
[junit4] Suite: org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/init-core-data-001
[junit4] 2> 203743 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 203743 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 203744 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 203744 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /v/e
[junit4] 2> 205957 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your
platform... using builtin-java classes where applicable
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 209544 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 210372 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.m.log Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via
org.mortbay.log.Slf4jLog
[junit4] 2> 210441 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 212442 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.m.log jetty-6.1.26
[junit4] 2> 212754 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs
to ./temp/Jetty_localhost_47452_hdfs____6nhw60/webapp
[junit4] 2> 214467 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:47452
[junit4] 2> 220090 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 220097 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.m.log jetty-6.1.26
[junit4] 2> 220265 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_53521_datanode____.ss8ncz/webapp
[junit4] 2> 221091 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:53521
[junit4] 2> 223712 WARN
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 223724 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.m.log jetty-6.1.26
[junit4] 2> 223777 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_43792_datanode____6cln2w/webapp
[junit4] 2> 226783 INFO
(SUITE-SharedFSAutoReplicaFailoverTest-seed#[8F84846AADE38943]-worker) [ ]
o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:43792
[junit4] 2> 227985 ERROR (DataNode:
[[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/tempDir-001/hdfsBaseDir/data/data3/,
[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/tempDir-001/hdfsBaseDir/data/data4/]]
heartbeating to localhost/127.0.0.1:57357) [ ]
o.a.h.h.s.d.DirectoryScanner
dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1
ms/sec. Assuming default value of 1000
[junit4] 2> 228012 ERROR (DataNode:
[[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/tempDir-001/hdfsBaseDir/data/data1/,
[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/tempDir-001/hdfsBaseDir/data/data2/]]
heartbeating to localhost/127.0.0.1:57357) [ ]
o.a.h.h.s.d.DirectoryScanner
dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1
ms/sec. Assuming default value of 1000
[junit4] 2> 228456 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x1125e5856866ca: from storage
DS-771019a8-18cc-4759-8154-7923750f1dfc node
DatanodeRegistration(127.0.0.1:56865,
datanodeUuid=59a0de29-1473-4c2a-aaf8-15aab33d829f, infoPort=58171,
infoSecurePort=0, ipcPort=40590,
storageInfo=lv=-56;cid=testClusterID;nsid=1684133339;c=0), blocks: 0,
hasStaleStorage: true, processing time: 2 msecs
[junit4] 2> 228457 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x1125e5856866ca: from storage
DS-81f269a9-ef1f-4e7f-bbae-824789291437 node
DatanodeRegistration(127.0.0.1:56865,
datanodeUuid=59a0de29-1473-4c2a-aaf8-15aab33d829f, infoPort=58171,
infoSecurePort=0, ipcPort=40590,
storageInfo=lv=-56;cid=testClusterID;nsid=1684133339;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 228467 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x1125e584f654c1: from storage
DS-6f1867bb-3c3b-49a1-8466-76f7bd52f547 node
DatanodeRegistration(127.0.0.1:43363,
datanodeUuid=b3b17c42-29b9-4800-afdc-ac499ac342de, infoPort=33861,
infoSecurePort=0, ipcPort=40847,
storageInfo=lv=-56;cid=testClusterID;nsid=1684133339;c=0), blocks: 0,
hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 228467 INFO (Block report processor) [ ] BlockStateChange
BLOCK* processReport 0x1125e584f654c1: from storage
DS-151a7f34-1006-4de7-b6ea-e4935f23f6e5 node
DatanodeRegistration(127.0.0.1:43363,
datanodeUuid=b3b17c42-29b9-4800-afdc-ac499ac342de, infoPort=33861,
infoSecurePort=0, ipcPort=40847,
storageInfo=lv=-56;cid=testClusterID;nsid=1684133339;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 231545 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 231562 INFO (Thread-260) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 231562 INFO (Thread-260) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 231602 ERROR (Thread-260) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 231730 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.ZkTestServer start zk server on port:59534
[junit4] 2> 231844 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 231846 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/conf/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 231847 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 231848 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 231849 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 231850 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 231851 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 231852 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 231853 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 231854 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 231855 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 232328 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/control-001/cores/collection1
[junit4] 2> 232358 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 232462 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@e65b7c7{/v/e,null,AVAILABLE}
[junit4] 2> 232462 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@65618004{HTTP/1.1,[http/1.1]}{127.0.0.1:58634}
[junit4] 2> 232463 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.Server Started @240616ms
[junit4] 2> 232463 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/v/e,
hostPort=58634,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/control-001/cores}
[junit4] 2> 232463 ERROR
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 232463 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.1
[junit4] 2> 232463 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 232463 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 232463 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-05T01:26:58.576Z
[junit4] 2> 232506 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 232507 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/control-001/solr.xml
[junit4] 2> 232542 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 232554 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59534/solr
[junit4] 2> 232626 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 232627 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:58634_v%2Fe
[junit4] 2> 232628 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.Overseer Overseer
(id=98603815251673092-127.0.0.1:58634_v%2Fe-n_0000000000) starting
[junit4] 2> 232769 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58634_v%2Fe
[junit4] 2> 232774 INFO
(OverseerStateUpdate-98603815251673092-127.0.0.1:58634_v%2Fe-n_0000000000)
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 233095 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/control-001/cores
[junit4] 2> 233096 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 233126 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 233135 INFO
(OverseerStateUpdate-98603815251673092-127.0.0.1:58634_v%2Fe-n_0000000000)
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 233197 WARN
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 233198 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.1
[junit4] 2> 233298 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 233679 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 233723 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection, trusted=true
[junit4] 2> 233830 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:57357/solr_hdfs_home
[junit4] 2> 233830 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 233830 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 233830 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/control-001/cores/collection1],
dataDir=[hdfs://localhost:57357/solr_hdfs_home/control_collection/core_node1/data/]
[junit4] 2> 233831 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@41c15ff1
[junit4] 2> 233831 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:57357/solr_hdfs_home/control_collection/core_node1/data/snapshot_metadata
[junit4] 2> 233890 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 233891 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 233891 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Creating new global HDFS BlockCache
[junit4] 2> 235825 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 235831 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:57357/solr_hdfs_home/control_collection/core_node1/data
[junit4] 2> 235982 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:57357/solr_hdfs_home/control_collection/core_node1/data/index
[junit4] 2> 236005 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 236005 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 236021 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 236021 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=2065041584,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 237121 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56865 is added to
blk_1073741825_1001{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-81f269a9-ef1f-4e7f-bbae-824789291437:NORMAL:127.0.0.1:56865|RBW],
ReplicaUC[[DISK]DS-6f1867bb-3c3b-49a1-8466-76f7bd52f547:NORMAL:127.0.0.1:43363|RBW]]}
size 71
[junit4] 2> 237124 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43363 is added to
blk_1073741825_1001 size 71
[junit4] 2> 237595 WARN
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 238034 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 238034 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 238034 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 238067 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 238067 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 238075 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=33, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4500553319634587]
[junit4] 2> 238555 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@2e211510[collection1] main]
[junit4] 2> 238556 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 238557 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 238559 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 238560 INFO
(searcherExecutor-321-thread-1-processing-n:127.0.0.1:58634_v%2Fe x:collection1
c:control_collection) [n:127.0.0.1:58634_v%2Fe c:control_collection
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@2e211510[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 238572 INFO
(coreLoadExecutor-320-thread-1-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe c:control_collection x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1577661051368898560
[junit4] 2> 238603 INFO
(coreZkRegister-313-thread-1-processing-n:127.0.0.1:58634_v%2Fe x:collection1
c:control_collection) [n:127.0.0.1:58634_v%2Fe c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas
found to continue.
[junit4] 2> 238603 INFO
(coreZkRegister-313-thread-1-processing-n:127.0.0.1:58634_v%2Fe x:collection1
c:control_collection) [n:127.0.0.1:58634_v%2Fe c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new
leader - try and sync
[junit4] 2> 238603 INFO
(coreZkRegister-313-thread-1-processing-n:127.0.0.1:58634_v%2Fe x:collection1
c:control_collection) [n:127.0.0.1:58634_v%2Fe c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:58634/v/e/collection1/
[junit4] 2> 238603 INFO
(coreZkRegister-313-thread-1-processing-n:127.0.0.1:58634_v%2Fe x:collection1
c:control_collection) [n:127.0.0.1:58634_v%2Fe c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 238603 INFO
(coreZkRegister-313-thread-1-processing-n:127.0.0.1:58634_v%2Fe x:collection1
c:control_collection) [n:127.0.0.1:58634_v%2Fe c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:58634/v/e/collection1/ has no replicas
[junit4] 2> 238603 INFO
(coreZkRegister-313-thread-1-processing-n:127.0.0.1:58634_v%2Fe x:collection1
c:control_collection) [n:127.0.0.1:58634_v%2Fe c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 238607 INFO
(coreZkRegister-313-thread-1-processing-n:127.0.0.1:58634_v%2Fe x:collection1
c:control_collection) [n:127.0.0.1:58634_v%2Fe c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new
leader: http://127.0.0.1:58634/v/e/collection1/ shard1
[junit4] 2> 238850 INFO
(coreZkRegister-313-thread-1-processing-n:127.0.0.1:58634_v%2Fe x:collection1
c:control_collection) [n:127.0.0.1:58634_v%2Fe c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery
necessary
[junit4] 2> 238853 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 238854 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59534/solr ready
[junit4] 2> 238856 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 238856 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 239113 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-1-001/cores/collection1
[junit4] 2> 239113 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-1-001
[junit4] 2> 239115 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 239266 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@398b1187{/v/e,null,AVAILABLE}
[junit4] 2> 239267 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@28256ce{HTTP/1.1,[http/1.1]}{127.0.0.1:60477}
[junit4] 2> 239267 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.Server Started @247420ms
[junit4] 2> 239267 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml,
hostContext=/v/e, hostPort=60477,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-1-001/cores}
[junit4] 2> 239267 ERROR
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 239279 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.1
[junit4] 2> 239280 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 239280 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 239280 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-05T01:27:05.393Z
[junit4] 2> 239348 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 239348 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-1-001/solr.xml
[junit4] 2> 239361 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 239361 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59534/solr
[junit4] 2> 239464 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 239467 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 239470 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:60477_v%2Fe
[junit4] 2> 239471 INFO (zkCallback-95-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 239471 INFO
(zkCallback-91-thread-2-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 239484 INFO
(zkCallback-101-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 239974 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-1-001/cores
[junit4] 2> 239974 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 240028 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 240032 INFO
(OverseerStateUpdate-98603815251673092-127.0.0.1:58634_v%2Fe-n_0000000000)
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 240134 INFO
(zkCallback-101-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 241073 WARN
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 241091 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.6.1
[junit4] 2> 241196 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 241738 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 242809 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 242810 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:57357/solr_hdfs_home
[junit4] 2> 242810 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 242810 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 242810 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-1-001/cores/collection1],
dataDir=[hdfs://localhost:57357/solr_hdfs_home/collection1/core_node1/data/]
[junit4] 2> 242810 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap
JMX monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@41c15ff1
[junit4] 2> 242810 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:57357/solr_hdfs_home/collection1/core_node1/data/snapshot_metadata
[junit4] 2> 242841 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 242841 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 242873 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 242874 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:57357/solr_hdfs_home/collection1/core_node1/data
[junit4] 2> 242940 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:57357/solr_hdfs_home/collection1/core_node1/data/index
[junit4] 2> 242950 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 242950 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 242970 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 242971 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=2065041584,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 243127 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56865 is added to
blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-151a7f34-1006-4de7-b6ea-e4935f23f6e5:NORMAL:127.0.0.1:43363|RBW],
ReplicaUC[[DISK]DS-81f269a9-ef1f-4e7f-bbae-824789291437:NORMAL:127.0.0.1:56865|FINALIZED]]}
size 0
[junit4] 2> 243157 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43363 is added to
blk_1073741826_1002 size 71
[junit4] 2> 243169 WARN
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.c.RequestHandlers
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class
= DumpRequestHandler,attributes = {initParams=a, name=/dump,
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 243451 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 243451 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 243451 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 243542 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 243542 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 243545 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=33, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4500553319634587]
[junit4] 2> 243639 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@7d17bcc5[collection1] main]
[junit4] 2> 243639 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 243640 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 243640 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 243642 INFO
(searcherExecutor-332-thread-1-processing-n:127.0.0.1:60477_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@7d17bcc5[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 243647 INFO
(coreLoadExecutor-331-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe c:collection1 x:collection1] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1577661056690421760
[junit4] 2> 243671 INFO
(coreZkRegister-326-thread-1-processing-n:127.0.0.1:60477_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:60477_v%2Fe c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 243671 INFO
(coreZkRegister-326-thread-1-processing-n:127.0.0.1:60477_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:60477_v%2Fe c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try
and sync
[junit4] 2> 243671 INFO
(coreZkRegister-326-thread-1-processing-n:127.0.0.1:60477_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:60477_v%2Fe c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:60477/v/e/collection1/
[junit4] 2> 243671 INFO
(coreZkRegister-326-thread-1-processing-n:127.0.0.1:60477_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:60477_v%2Fe c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 243671 INFO
(coreZkRegister-326-thread-1-processing-n:127.0.0.1:60477_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:60477_v%2Fe c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60477/v/e/collection1/ has
no replicas
[junit4] 2> 243671 INFO
(coreZkRegister-326-thread-1-processing-n:127.0.0.1:60477_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:60477_v%2Fe c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas
participating in election, clear LIR
[junit4] 2> 243672 INFO
(zkCallback-101-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 243675 INFO
(coreZkRegister-326-thread-1-processing-n:127.0.0.1:60477_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:60477_v%2Fe c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:60477/v/e/collection1/ shard2
[junit4] 2> 243815 INFO
(zkCallback-101-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 243865 INFO
(coreZkRegister-326-thread-1-processing-n:127.0.0.1:60477_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:60477_v%2Fe c:collection1 s:shard2 r:core_node1
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 243970 INFO
(zkCallback-101-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 244353 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-2-001/cores/collection1
[junit4] 2> 244358 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-2-001
[junit4] 2> 244359 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 244442 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4621cfe3{/v/e,null,AVAILABLE}
[junit4] 2> 244442 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3d5d79a8{HTTP/1.1,[http/1.1]}{127.0.0.1:57301}
[junit4] 2> 244442 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.Server Started @252596ms
[junit4] 2> 244443 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml,
hostContext=/v/e, hostPort=57301,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-2-001/cores}
[junit4] 2> 244443 ERROR
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 244451 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.1
[junit4] 2> 244451 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 244451 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 244451 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-05T01:27:10.564Z
[junit4] 2> 244526 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 244526 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-2-001/solr.xml
[junit4] 2> 244547 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 244550 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59534/solr
[junit4] 2> 244762 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:57301_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 244765 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:57301_v%2Fe ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 244767 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:57301_v%2Fe ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:57301_v%2Fe
[junit4] 2> 244774 INFO
(zkCallback-101-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 244776 INFO (zkCallback-95-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 244774 INFO
(zkCallback-91-thread-2-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 244778 INFO
(zkCallback-107-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 244953 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:57301_v%2Fe ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-2-001/cores
[junit4] 2> 244953 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:57301_v%2Fe ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 245022 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 245027 INFO
(OverseerStateUpdate-98603815251673092-127.0.0.1:58634_v%2Fe-n_0000000000)
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 245144 INFO
(zkCallback-101-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 245144 INFO
(zkCallback-107-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 246059 WARN
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 246059 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.6.1
[junit4] 2> 246211 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 247152 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 247201 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 247220 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:57357/solr_hdfs_home
[junit4] 2> 247220 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 247220 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 247220 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-2-001/cores/collection1],
dataDir=[hdfs://localhost:57357/solr_hdfs_home/collection1/core_node2/data/]
[junit4] 2> 247220 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap
JMX monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@41c15ff1
[junit4] 2> 247221 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:57357/solr_hdfs_home/collection1/core_node2/data/snapshot_metadata
[junit4] 2> 247234 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 247235 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 247258 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 247260 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:57357/solr_hdfs_home/collection1/core_node2/data
[junit4] 2> 247368 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:57357/solr_hdfs_home/collection1/core_node2/data/index
[junit4] 2> 247390 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 247390 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[4194304] will allocate [1] slabs and use ~[4194304] bytes
[junit4] 2> 247405 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 247406 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=2065041584,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 247502 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56865 is added to
blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-151a7f34-1006-4de7-b6ea-e4935f23f6e5:NORMAL:127.0.0.1:43363|RBW],
ReplicaUC[[DISK]DS-771019a8-18cc-4759-8154-7923750f1dfc:NORMAL:127.0.0.1:56865|FINALIZED]]}
size 0
[junit4] 2> 247504 INFO (Block report processor) [ ] BlockStateChange
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43363 is added to
blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-771019a8-18cc-4759-8154-7923750f1dfc:NORMAL:127.0.0.1:56865|FINALIZED],
ReplicaUC[[DISK]DS-6f1867bb-3c3b-49a1-8466-76f7bd52f547:NORMAL:127.0.0.1:43363|FINALIZED]]}
size 0
[junit4] 2> 247520 WARN
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.c.RequestHandlers
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class
= DumpRequestHandler,attributes = {initParams=a, name=/dump,
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 248012 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 248012 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 248012 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 248064 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 248064 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 248067 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=33, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.4500553319634587]
[junit4] 2> 248097 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@62f89ae0[collection1] main]
[junit4] 2> 248098 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 248099 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 248099 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 248100 INFO
(searcherExecutor-343-thread-1-processing-n:127.0.0.1:57301_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@62f89ae0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 248119 INFO
(coreLoadExecutor-342-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe c:collection1 x:collection1] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1577661061379653632
[junit4] 2> 248156 INFO
(zkCallback-101-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 248159 INFO
(zkCallback-107-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 248160 INFO
(coreZkRegister-337-thread-1-processing-n:127.0.0.1:57301_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:57301_v%2Fe c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 248160 INFO
(coreZkRegister-337-thread-1-processing-n:127.0.0.1:57301_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:57301_v%2Fe c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try
and sync
[junit4] 2> 248160 INFO
(coreZkRegister-337-thread-1-processing-n:127.0.0.1:57301_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:57301_v%2Fe c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:57301/v/e/collection1/
[junit4] 2> 248160 INFO
(coreZkRegister-337-thread-1-processing-n:127.0.0.1:57301_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:57301_v%2Fe c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 248160 INFO
(coreZkRegister-337-thread-1-processing-n:127.0.0.1:57301_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:57301_v%2Fe c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:57301/v/e/collection1/ has
no replicas
[junit4] 2> 248160 INFO
(coreZkRegister-337-thread-1-processing-n:127.0.0.1:57301_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:57301_v%2Fe c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas
participating in election, clear LIR
[junit4] 2> 248543 INFO
(coreZkRegister-337-thread-1-processing-n:127.0.0.1:57301_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:57301_v%2Fe c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:57301/v/e/collection1/ shard1
[junit4] 2> 248605 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-3-001/cores/collection1
[junit4] 2> 248606 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-3-001
[junit4] 2> 248607 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 248616 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2fb2f2dc{/v/e,null,AVAILABLE}
[junit4] 2> 248616 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@36453b93{HTTP/1.1,[http/1.1]}{127.0.0.1:42307}
[junit4] 2> 248616 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.e.j.s.Server Started @256769ms
[junit4] 2> 248616 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml,
hostContext=/v/e, hostPort=42307,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-3-001/cores}
[junit4] 2> 248616 ERROR
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 248617 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.1
[junit4] 2> 248617 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 248617 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 248617 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-09-05T01:27:14.730Z
[junit4] 2> 248632 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 248632 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-3-001/solr.xml
[junit4] 2> 248641 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 248641 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59534/solr
[junit4] 2> 248646 INFO
(zkCallback-107-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 248646 INFO
(zkCallback-101-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 248657 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:42307_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 248658 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:42307_v%2Fe ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 248659 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:42307_v%2Fe ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42307_v%2Fe
[junit4] 2> 248660 INFO
(zkCallback-107-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 248660 INFO (zkCallback-95-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 248660 INFO
(zkCallback-101-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 248660 INFO
(zkCallback-91-thread-2-processing-n:127.0.0.1:58634_v%2Fe)
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 248672 INFO
(zkCallback-113-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 248694 INFO
(coreZkRegister-337-thread-1-processing-n:127.0.0.1:57301_v%2Fe x:collection1
c:collection1) [n:127.0.0.1:57301_v%2Fe c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 248714 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:42307_v%2Fe ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-3-001/cores
[junit4] 2> 248714 INFO
(TEST-SharedFSAutoReplicaFailoverTest.test-seed#[8F84846AADE38943])
[n:127.0.0.1:42307_v%2Fe ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 248726 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 248727 INFO
(OverseerStateUpdate-98603815251673092-127.0.0.1:58634_v%2Fe-n_0000000000)
[n:127.0.0.1:58634_v%2Fe ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 248866 INFO
(zkCallback-101-thread-1-processing-n:127.0.0.1:60477_v%2Fe)
[n:127.0.0.1:60477_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 248866 INFO
(zkCallback-107-thread-1-processing-n:127.0.0.1:57301_v%2Fe)
[n:127.0.0.1:57301_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 248866 INFO
(zkCallback-113-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 249765 WARN
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 249766 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.6.1
[junit4] 2> 249819 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 250208 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 250268 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
collection1, trusted=true
[junit4] 2> 250271 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:57357/solr_hdfs_home
[junit4] 2> 250272 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 250272 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 250272 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1] o.a.s.c.SolrCore
[[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_8F84846AADE38943-001/shard-3-001/cores/collection1],
dataDir=[hdfs://localhost:57357/solr_hdfs_home/collection1/core_node3/data/]
[junit4] 2> 250272 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap
JMX monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@41c15ff1
[junit4] 2> 250272 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:57357/solr_hdfs_home/collection1/core_node3/data/snapshot_metadata
[junit4] 2> 250353 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42307_v%2Fe c:collection1 x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 250353 INFO
(coreLoadExecutor-353-thread-1-processing-n:127.0.0.1:42307_v%2Fe)
[n:127.0.0.1:42
[...truncated too long message...]
OTSTRAP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 509174 INFO (qtp548863479-2296) [n:127.0.0.1:39831_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.S.Request [cdcr-target_shard1_replica1] webapp=/solr path=/cdcr
params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 509175 WARN
(cdcr-bootstrap-status-431-thread-1-processing-n:127.0.0.1:52142_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node2)
[n:127.0.0.1:52142_solr c:cdcr-source s:shard1 r:core_node2
x:cdcr-source_shard1_replica1] o.a.s.h.CdcrReplicatorManager Cancelling waiting
for bootstrap on target: cdcr-target shard: shard1 to complete
[junit4] 2> 509273 INFO (coreCloseExecutor-936-thread-1)
[n:127.0.0.1:52142_solr c:cdcr-source s:shard1 r:core_node2
x:cdcr-source_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.cdcr-source.shard1.replica1
[junit4] 2> 509670 INFO (jetty-closer-406-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98603823430631435-127.0.0.1:52142_solr-n_0000000001) closing
[junit4] 2> 509670 INFO
(OverseerStateUpdate-98603823430631435-127.0.0.1:52142_solr-n_0000000001)
[n:127.0.0.1:52142_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:52142_solr
[junit4] 2> 511182 WARN
(zkCallback-425-thread-2-processing-n:127.0.0.1:52142_solr)
[n:127.0.0.1:52142_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 511183 INFO (jetty-closer-406-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@704142b6{/solr,null,UNAVAILABLE}
[junit4] 2> 511183 ERROR
(TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[8F84846AADE38943])
[ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so
ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 511184 INFO
(TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[8F84846AADE38943])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:47690 47690
[junit4] 2> 511316 INFO (Thread-486) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:47690 47690
[junit4] 2> 511316 WARN (Thread-486) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/configs/cdcr-source
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 2 /solr/collections/cdcr-source/cdcr/state/process
[junit4] 2> 2 /solr/collections/cdcr-source/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/collections
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/live_nodes
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 511335 INFO (jetty-closer-394-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@604de7b6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 511336 INFO (jetty-closer-394-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1692596823
[junit4] 2> 511336 INFO (jetty-closer-394-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 511336 INFO (jetty-closer-394-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 511336 INFO (jetty-closer-394-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 511355 INFO (coreCloseExecutor-938-thread-1)
[n:127.0.0.1:39831_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.c.SolrCore [cdcr-target_shard1_replica1]
CLOSING SolrCore org.apache.solr.core.SolrCore@3218c1c2
[junit4] 2> 511355 INFO (coreCloseExecutor-938-thread-1)
[n:127.0.0.1:39831_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.CdcrRequestHandler Solr core is being
closed - shutting down CDCR handler @ cdcr-target:shard1
[junit4] 2> 511362 INFO
(zkCallback-400-thread-2-processing-n:127.0.0.1:39831_solr)
[n:127.0.0.1:39831_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/cdcr-target/state.json] for collection [cdcr-target] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 511415 INFO (coreCloseExecutor-938-thread-1)
[n:127.0.0.1:39831_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.cdcr-target.shard1.replica1
[junit4] 2> 511416 INFO (jetty-closer-394-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98603823361818627-127.0.0.1:39831_solr-n_0000000000) closing
[junit4] 2> 511416 INFO
(OverseerStateUpdate-98603823361818627-127.0.0.1:39831_solr-n_0000000000)
[n:127.0.0.1:39831_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:39831_solr
[junit4] 2> 511425 WARN
(zkCallback-400-thread-3-processing-n:127.0.0.1:39831_solr)
[n:127.0.0.1:39831_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 511430 INFO (jetty-closer-394-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@6d1e9bb8{/solr,null,UNAVAILABLE}
[junit4] 2> 511430 ERROR
(TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[8F84846AADE38943])
[ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so
ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 511431 INFO
(TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[8F84846AADE38943])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54217 54217
[junit4] 2> 511534 INFO (Thread-476) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:54217 54217
[junit4] 2> 511534 WARN (Thread-476) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 2 /solr/collections/cdcr-target/cdcr/state/process
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> 511535 INFO
(TEST-CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap-seed#[8F84846AADE38943])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testConvertClusterToCdcrAndBootstrap
[junit4] 2> NOTE: download the large Jenkins line-docs file by running
'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CdcrBootstrapTest
-Dtests.method=testConvertClusterToCdcrAndBootstrap
-Dtests.seed=8F84846AADE38943 -Dtests.multiplier=2 -Dtests.nightly=true
-Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/test-data/enwiki.random.lines.txt
-Dtests.locale=en-SG -Dtests.timezone=IST -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 154s J1 |
CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap <<<
[junit4] > Throwable #1: java.lang.AssertionError: Document mismatch on
target after sync expected:<10000> but was:<0>
[junit4] > at
__randomizedtesting.SeedInfo.seed([8F84846AADE38943:5853AB1D19BC1104]:0)
[junit4] > at
org.apache.solr.cloud.CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap(CdcrBootstrapTest.java:134)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 511542 INFO
(SUITE-CdcrBootstrapTest-seed#[8F84846AADE38943]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.6/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.CdcrBootstrapTest_8F84846AADE38943-001
[junit4] 2> Sep 05, 2017 1:31:35 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene62,
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=en-SG, timezone=IST
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_144 (64-bit)/cpus=4,threads=1,free=211776360,total=459800576
[junit4] 2> NOTE: All tests run in this JVM:
[DistributedDebugComponentTest, ZkCLITest,
DistributedQueryComponentOptimizationTest, TestCSVResponseWriter,
TestPartialUpdateDeduplication, BlockJoinFacetDistribTest,
TestImpersonationWithHadoopAuth, TermVectorComponentTest, TestCharFilters,
TestManagedStopFilterFactory, TestCollationFieldDocValues,
TestManagedSchemaAPI, TestTestInjection, TestUseDocValuesAsStored2,
CollectionsAPISolrJTest, MoreLikeThisHandlerTest, SaslZkACLProviderTest,
TestExclusionRuleCollectionAccess, TestSolrIndexConfig, SpellCheckCollatorTest,
TestFieldCollectionResource, TestSearchPerf, TestIntervalFaceting,
TestConfigReload, LeaderInitiatedRecoveryOnShardRestartTest,
TestShardHandlerFactory, IndexBasedSpellCheckerTest, TestCloudDeleteByQuery,
CdcrBootstrapTest]
[junit4] Completed [68/713 (2!)] on J1 in 223.17s, 3 tests, 1 failure <<<
FAILURES!
[...truncated 45607 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
