Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/1925/
Java: 32bit/jdk-9-ea+138 -client -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.PeerSyncReplicationTest.test
Error Message:
expected:<204> but was:<190>
Stack Trace:
java.lang.AssertionError: expected:<204> but was:<190>
at
__randomizedtesting.SeedInfo.seed([EBEDB14F5CD8BAE7:63B98E95F224D71F]: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.PeerSyncReplicationTest.bringUpDeadNodeAndEnsureNoReplication(PeerSyncReplicationTest.java:280)
at
org.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:157)
at
jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native
Method)
at
jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
at
jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
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:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
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:367)
at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
Build Log:
[...truncated 12179 lines...]
[junit4] Suite: org.apache.solr.cloud.PeerSyncReplicationTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/init-core-data-001
[junit4] 2> 1363086 INFO
(SUITE-PeerSyncReplicationTest-seed#[EBEDB14F5CD8BAE7]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 1363086 INFO
(SUITE-PeerSyncReplicationTest-seed#[EBEDB14F5CD8BAE7]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property:
/zn_ym/o
[junit4] 2> 1363088 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1363088 INFO (Thread-2660) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1363088 INFO (Thread-2660) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1363188 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ZkTestServer start zk server on port:41089
[junit4] 2> 1363193 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 1363194 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 1363194 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1363195 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 1363195 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 1363196 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 1363197 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 1363197 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1363198 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1363198 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 1363199 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractZkTestCase put
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 1363264 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/control-001/cores/collection1
[junit4] 2> 1363265 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1363265 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1578519{/zn_ym/o,null,AVAILABLE}
[junit4] 2> 1363266 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@177d6c4{HTTP/1.1,[http/1.1]}{127.0.0.1:35467}
[junit4] 2> 1363266 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.Server Started @1365321ms
[junit4] 2> 1363266 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/zn_ym/o,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/tempDir-001/control/data,
hostPort=35467,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/control-001/cores}
[junit4] 2> 1363266 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 1363266 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1363266 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1363266 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-11T16:37:08.502564Z
[junit4] 2> 1363268 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1363268 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/control-001/solr.xml
[junit4] 2> 1363272 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1363273 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41089/solr
[junit4] 2> 1363282 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader
127.0.0.1:35467_zn_ym%2Fo
[junit4] 2> 1363282 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.Overseer Overseer
(id=96744494093107204-127.0.0.1:35467_zn_ym%2Fo-n_0000000000) starting
[junit4] 2> 1363286 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:35467_zn_ym%2Fo
[junit4] 2> 1363287 INFO (zkCallback-18800-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1363302 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/control-001/cores
[junit4] 2> 1363302 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1363304 INFO
(OverseerStateUpdate-96744494093107204-127.0.0.1:35467_zn_ym%2Fo-n_0000000000)
[ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1364311 WARN (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 1364311 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1364321 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1364399 WARN (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1364401 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1364406 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 1364406 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/control-001/cores/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/control-001/cores/collection1/data/]
[junit4] 2> 1364406 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@87f0dc
[junit4] 2> 1364408 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=465723418,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1364430 WARN (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 1364434 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1364434 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1364435 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1364435 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1364435 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1364435 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@77dc11[collection1] main]
[junit4] 2> 1364436 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1364436 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1364436 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1364437 INFO (searcherExecutor-10029-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@77dc11[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1364437 INFO (coreLoadExecutor-10028-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1547911906903195648
[junit4] 2> 1364441 INFO (coreZkRegister-10021-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1364441 INFO (coreZkRegister-10021-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1364441 INFO (coreZkRegister-10021-thread-1) [ ]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:35467/zn_ym/o/collection1/
[junit4] 2> 1364441 INFO (coreZkRegister-10021-thread-1) [ ]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1364441 INFO (coreZkRegister-10021-thread-1) [ ]
o.a.s.c.SyncStrategy http://127.0.0.1:35467/zn_ym/o/collection1/ has no replicas
[junit4] 2> 1364442 INFO (coreZkRegister-10021-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:35467/zn_ym/o/collection1/ shard1
[junit4] 2> 1364593 INFO (coreZkRegister-10021-thread-1) [ ]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1364806 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1364807 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 1364870 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-1-001/cores/collection1
[junit4] 2> 1364870 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-1-001
[junit4] 2> 1364871 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1364871 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1132e31{/zn_ym/o,null,AVAILABLE}
[junit4] 2> 1364872 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@1f3a313{HTTP/1.1,[http/1.1]}{127.0.0.1:36254}
[junit4] 2> 1364872 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.Server Started @1366927ms
[junit4] 2> 1364872 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/zn_ym/o,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/tempDir-001/jetty1,
hostPort=36254,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-1-001/cores}
[junit4] 2> 1364872 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 1364872 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1364872 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1364872 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-11T16:37:10.108931Z
[junit4] 2> 1364874 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1364874 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-1-001/solr.xml
[junit4] 2> 1364878 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1364878 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41089/solr
[junit4] 2> 1364883 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1364885 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36254_zn_ym%2Fo
[junit4] 2> 1364886 INFO (zkCallback-18809-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1364886 INFO (zkCallback-18800-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1364886 INFO (zkCallback-18804-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1364903 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-1-001/cores
[junit4] 2> 1364903 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1364905 INFO
(OverseerStateUpdate-96744494093107204-127.0.0.1:35467_zn_ym%2Fo-n_0000000000)
[ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1365911 WARN (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 1365912 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1365922 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1366007 WARN (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1366010 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1366017 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1
[junit4] 2> 1366017 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-1-001/cores/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 1366017 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@87f0dc
[junit4] 2> 1366019 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=465723418,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1366080 WARN (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 1366089 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1366089 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1366110 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1366110 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1366110 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1366111 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1423733[collection1] main]
[junit4] 2> 1366111 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1366112 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1366112 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1366112 INFO (searcherExecutor-10040-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1423733[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1366113 INFO (coreLoadExecutor-10039-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1547911908660609024
[junit4] 2> 1366118 INFO (coreZkRegister-10034-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1366118 INFO (coreZkRegister-10034-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1366118 INFO (coreZkRegister-10034-thread-1) [ ]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:36254/zn_ym/o/collection1/
[junit4] 2> 1366118 INFO (coreZkRegister-10034-thread-1) [ ]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1366118 INFO (coreZkRegister-10034-thread-1) [ ]
o.a.s.c.SyncStrategy http://127.0.0.1:36254/zn_ym/o/collection1/ has no replicas
[junit4] 2> 1366121 INFO (coreZkRegister-10034-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:36254/zn_ym/o/collection1/ shard1
[junit4] 2> 1366272 INFO (coreZkRegister-10034-thread-1) [ ]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1366466 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-2-001/cores/collection1
[junit4] 2> 1366467 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-2-001
[junit4] 2> 1366467 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1366468 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@12a9063{/zn_ym/o,null,AVAILABLE}
[junit4] 2> 1366468 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@46cb0f{HTTP/1.1,[http/1.1]}{127.0.0.1:45827}
[junit4] 2> 1366468 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.Server Started @1368523ms
[junit4] 2> 1366468 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/zn_ym/o,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/tempDir-001/jetty2,
hostPort=45827,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-2-001/cores}
[junit4] 2> 1366469 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 1366469 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1366469 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1366469 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-11T16:37:11.705356Z
[junit4] 2> 1366471 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1366471 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-2-001/solr.xml
[junit4] 2> 1366474 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1366475 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41089/solr
[junit4] 2> 1366480 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1366481 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45827_zn_ym%2Fo
[junit4] 2> 1366482 INFO (zkCallback-18804-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1366482 INFO (zkCallback-18809-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1366482 INFO (zkCallback-18815-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1366482 INFO (zkCallback-18800-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1366512 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-2-001/cores
[junit4] 2> 1366512 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1366513 INFO
(OverseerStateUpdate-96744494093107204-127.0.0.1:35467_zn_ym%2Fo-n_0000000000)
[ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1367520 WARN (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 1367521 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1367531 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1367618 WARN (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1367620 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1367626 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1
[junit4] 2> 1367626 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-2-001/cores/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 1367626 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@87f0dc
[junit4] 2> 1367627 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=465723418,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1367651 WARN (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 1367658 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1367658 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1367658 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1367658 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1367659 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1367659 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1894406[collection1] main]
[junit4] 2> 1367659 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1367660 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1367660 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1367660 INFO (searcherExecutor-10051-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1894406[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1367661 INFO (coreLoadExecutor-10050-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1547911910283804672
[junit4] 2> 1367663 INFO (coreZkRegister-10045-thread-1) [ ]
o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 1367664 INFO (updateExecutor-18812-thread-1) [ ]
o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1367664 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 1367664 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 1367664 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
[junit4] 2> 1367664 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 1367664 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering,
leader is [http://127.0.0.1:36254/zn_ym/o/collection1/] and I am
[http://127.0.0.1:45827/zn_ym/o/collection1/]
[junit4] 2> 1367665 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.c.RecoveryStrategy Sending prep recovery command to
[http://127.0.0.1:36254/zn_ym/o]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:45827_zn_ym%252Fo&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1367667 INFO (qtp16757665-72648) [ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node2, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1367667 INFO (qtp16757665-72648) [ ]
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard1 of collection1) have state: recovering
[junit4] 2> 1367667 INFO (qtp16757665-72648) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=down, localState=active,
nodeName=127.0.0.1:45827_zn_ym%2Fo, coreNodeName=core_node2,
onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"http://127.0.0.1:45827/zn_ym/o","node_name":"127.0.0.1:45827_zn_ym%2Fo","state":"down"}
[junit4] 2> 1368119 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-3-001/cores/collection1
[junit4] 2> 1368120 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-3-001
[junit4] 2> 1368121 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1368121 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@170feaf{/zn_ym/o,null,AVAILABLE}
[junit4] 2> 1368122 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@1557826{HTTP/1.1,[http/1.1]}{127.0.0.1:44421}
[junit4] 2> 1368122 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.Server Started @1370177ms
[junit4] 2> 1368122 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/zn_ym/o,
solrconfig=solrconfig.xml,
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/tempDir-001/jetty3,
hostPort=44421,
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-3-001/cores}
[junit4] 2> 1368122 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 1368122 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1368122 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1368123 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-11T16:37:13.359026Z
[junit4] 2> 1368125 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1368125 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-3-001/solr.xml
[junit4] 2> 1368131 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1368132 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41089/solr
[junit4] 2> 1368138 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1368140 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44421_zn_ym%2Fo
[junit4] 2> 1368141 INFO (zkCallback-18804-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1368141 INFO (zkCallback-18809-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1368141 INFO (zkCallback-18815-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1368142 INFO (zkCallback-18822-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1368141 INFO (zkCallback-18800-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1368213 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-3-001/cores
[junit4] 2> 1368213 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1368214 INFO
(OverseerStateUpdate-96744494093107204-127.0.0.1:35467_zn_ym%2Fo-n_0000000000)
[ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1368667 INFO (qtp16757665-72648) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=recovering, localState=active,
nodeName=127.0.0.1:45827_zn_ym%2Fo, coreNodeName=core_node2,
onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"http://127.0.0.1:45827/zn_ym/o","node_name":"127.0.0.1:45827_zn_ym%2Fo","state":"recovering"}
[junit4] 2> 1368668 INFO (qtp16757665-72648) [ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node2, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1368668 INFO (qtp16757665-72648) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:45827_zn_ym%252Fo&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node2&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1000
[junit4] 2> 1369168 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.c.RecoveryStrategy Attempting to PeerSync from
[http://127.0.0.1:36254/zn_ym/o/collection1/] - recoveringAfterStartup=[true]
[junit4] 2> 1369168 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.u.PeerSync PeerSync: core=collection1 url=http://127.0.0.1:45827/zn_ym/o
START replicas=[http://127.0.0.1:36254/zn_ym/o/collection1/] nUpdates=1000
[junit4] 2> 1369170 INFO (qtp16757665-72643) [ ]
o.a.s.u.IndexFingerprint IndexFingerprint millis:0.0
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0,
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
[junit4] 2> 1369170 INFO (qtp16757665-72643) [ ] o.a.s.c.S.Request
[collection1] webapp=/zn_ym/o path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1369170 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.u.IndexFingerprint IndexFingerprint millis:0.0
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0,
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
[junit4] 2> 1369170 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync
[junit4] 2> 1369171 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1369171 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1369171 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1369171 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful.
[junit4] 2> 1369171 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync.
[junit4] 2> 1369171 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 1369171 INFO (recoveryExecutor-18813-thread-1) [ ]
o.a.s.c.RecoveryStrategy Registering as Active after recovery.
[junit4] 2> 1369221 WARN (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 1369221 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1369231 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1369348 WARN (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1369350 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1369359 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1
[junit4] 2> 1369359 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-3-001/cores/collection1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 1369360 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@87f0dc
[junit4] 2> 1369361 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=465723418,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1369388 WARN (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 1369397 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1369397 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1369398 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1369398 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1369399 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1369399 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@92e5ea[collection1] main]
[junit4] 2> 1369400 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1369400 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1369400 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1369401 INFO (coreLoadExecutor-10061-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1547911912108326912
[junit4] 2> 1369404 INFO (searcherExecutor-10062-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@92e5ea[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1369405 INFO (coreZkRegister-10056-thread-1) [ ]
o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 1369405 INFO (updateExecutor-18819-thread-1) [ ]
o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1369405 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 1369405 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 1369405 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
[junit4] 2> 1369405 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 1369405 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering,
leader is [http://127.0.0.1:36254/zn_ym/o/collection1/] and I am
[http://127.0.0.1:44421/zn_ym/o/collection1/]
[junit4] 2> 1369406 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.c.RecoveryStrategy Sending prep recovery command to
[http://127.0.0.1:36254/zn_ym/o]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:44421_zn_ym%252Fo&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1369407 INFO (qtp16757665-72650) [ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1369407 INFO (qtp16757665-72650) [ ]
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard1 of collection1) have state: recovering
[junit4] 2> 1369407 INFO (qtp16757665-72650) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=down, localState=active,
nodeName=127.0.0.1:44421_zn_ym%2Fo, coreNodeName=core_node3,
onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:44421/zn_ym/o","node_name":"127.0.0.1:44421_zn_ym%2Fo","state":"down"}
[junit4] 2> 1369715 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1369715 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Wait for recoveries to finish - wait 30
for each attempt
[junit4] 2> 1369715 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1 failOnTimeout:true timeout (sec):30
[junit4] 2> 1370408 INFO (qtp16757665-72650) [ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=recovering, localState=active,
nodeName=127.0.0.1:44421_zn_ym%2Fo, coreNodeName=core_node3,
onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:44421/zn_ym/o","node_name":"127.0.0.1:44421_zn_ym%2Fo","state":"recovering"}
[junit4] 2> 1370408 INFO (qtp16757665-72650) [ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1370408 INFO (qtp16757665-72650) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:44421_zn_ym%252Fo&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1000
[junit4] 2> 1370908 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.c.RecoveryStrategy Attempting to PeerSync from
[http://127.0.0.1:36254/zn_ym/o/collection1/] - recoveringAfterStartup=[true]
[junit4] 2> 1370908 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.u.PeerSync PeerSync: core=collection1 url=http://127.0.0.1:44421/zn_ym/o
START replicas=[http://127.0.0.1:36254/zn_ym/o/collection1/] nUpdates=1000
[junit4] 2> 1370910 INFO (qtp16757665-72650) [ ] o.a.s.c.S.Request
[collection1] webapp=/zn_ym/o path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1370910 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.u.IndexFingerprint IndexFingerprint millis:0.0
result:{maxVersionSpecified=9223372036854775807, maxVersionEncountered=0,
maxInHash=0, versionsHash=0, numVersions=0, numDocs=0, maxDoc=0}
[junit4] 2> 1370910 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync
[junit4] 2> 1370910 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1370910 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1370910 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1370910 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful.
[junit4] 2> 1370910 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync.
[junit4] 2> 1370910 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 1370910 INFO (recoveryExecutor-18820-thread-1) [ ]
o.a.s.c.RecoveryStrategy Registering as Active after recovery.
[junit4] 2> 1371715 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 1371716 INFO (qtp32976528-72613) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1371717 INFO (qtp32976528-72613) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1371717 INFO (qtp32976528-72613) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1371717 INFO (qtp32976528-72613) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 0
[junit4] 2> 1371719 INFO (qtp16757665-72648) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1371719 INFO (qtp4303715-72710) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1371719 INFO (qtp16757665-72648) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1371719 INFO (qtp4303715-72710) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1371719 INFO (qtp25213247-72676) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1371719 INFO (qtp25213247-72676) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1371719 INFO (qtp4303715-72710) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1371719 INFO (qtp4303715-72710) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 1371719 INFO (qtp25213247-72676) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1371719 INFO (qtp16757665-72648) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1371719 INFO (qtp25213247-72676) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 1371720 INFO (qtp16757665-72648) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 1371720 INFO (qtp16757665-72645) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 2
[junit4] 2> 1371722 INFO (qtp16757665-72650) [ ] o.a.s.c.S.Request
[collection1] webapp=/zn_ym/o path=/select
params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2}
hits=0 status=0 QTime=0
[junit4] 2> 1371722 INFO (qtp25213247-72678) [ ] o.a.s.c.S.Request
[collection1] webapp=/zn_ym/o path=/select
params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2}
hits=0 status=0 QTime=0
[junit4] 2> 1371723 INFO (qtp4303715-72712) [ ] o.a.s.c.S.Request
[collection1] webapp=/zn_ym/o path=/select
params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2}
hits=0 status=0 QTime=0
[junit4] 2> 1373726 INFO (qtp32976528-72614) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{deleteByQuery=*:* (-1547911916642369536)} 0 1
[junit4] 2> 1373731 INFO (qtp4303715-72711) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&_version_=-1547911916645515264&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1547911916645515264)} 0 1
[junit4] 2> 1373731 INFO (qtp25213247-72677) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&_version_=-1547911916645515264&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1547911916645515264)} 0 1
[junit4] 2> 1373731 INFO (qtp16757665-72646) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{deleteByQuery=*:* (-1547911916645515264)} 0 3
[junit4] 2> 1373737 INFO (qtp25213247-72676) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[0
(1547911916652855296)]} 0 0
[junit4] 2> 1373737 INFO (qtp4303715-72710) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[0
(1547911916652855296)]} 0 0
[junit4] 2> 1373737 INFO (qtp16757665-72645) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[0 (1547911916652855296)]} 0 3
[junit4] 2> 1373741 INFO (qtp25213247-72672) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[1
(1547911916657049600)]} 0 0
[junit4] 2> 1373741 INFO (qtp4303715-72706) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[1
(1547911916657049600)]} 0 0
[junit4] 2> 1373741 INFO (qtp16757665-72643) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[1 (1547911916657049600)]} 0 2
[junit4] 2> 1373744 INFO (qtp25213247-72678) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[2
(1547911916660195328)]} 0 0
[junit4] 2> 1373744 INFO (qtp4303715-72712) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[2
(1547911916660195328)]} 0 0
[junit4] 2> 1373744 INFO (qtp16757665-72650) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[2 (1547911916660195328)]} 0 2
[junit4] 2> 1373748 INFO (qtp4303715-72711) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[3
(1547911916664389632)]} 0 0
[junit4] 2> 1373748 INFO (qtp25213247-72677) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[3
(1547911916664389632)]} 0 0
[junit4] 2> 1373749 INFO (qtp16757665-72646) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[3 (1547911916664389632)]} 0 2
[junit4] 2> 1373752 INFO (qtp4303715-72710) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[4
(1547911916668583936)]} 0 0
[junit4] 2> 1373752 INFO (qtp25213247-72676) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[4
(1547911916668583936)]} 0 0
[junit4] 2> 1373752 INFO (qtp16757665-72645) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[4 (1547911916668583936)]} 0 1
[junit4] 2> 1373755 INFO (qtp4303715-72706) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[5
(1547911916671729664)]} 0 0
[junit4] 2> 1373755 INFO (qtp25213247-72676) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[5
(1547911916671729664)]} 0 0
[junit4] 2> 1373755 INFO (qtp16757665-72643) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[5 (1547911916671729664)]} 0 2
[junit4] 2> 1373758 INFO (qtp25213247-72678) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[6
(1547911916675923968)]} 0 0
[junit4] 2> 1373758 INFO (qtp4303715-72712) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[6
(1547911916675923968)]} 0 0
[junit4] 2> 1373759 INFO (qtp16757665-72650) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[6 (1547911916675923968)]} 0 1
[junit4] 2> 1373762 INFO (qtp25213247-72677) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[7
(1547911916680118272)]} 0 0
[junit4] 2> 1373762 INFO (qtp4303715-72712) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[7
(1547911916680118272)]} 0 0
[junit4] 2> 1373762 INFO (qtp16757665-72646) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[7 (1547911916680118272)]} 0 1
[junit4] 2> 1373766 INFO (qtp4303715-72710) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[8
(1547911916683264000)]} 0 0
[junit4] 2> 1373766 INFO (qtp25213247-72672) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[8
(1547911916683264000)]} 0 0
[junit4] 2> 1373766 INFO (qtp16757665-72645) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[8 (1547911916683264000)]} 0 2
[junit4] 2> 1373770 INFO (qtp4303715-72706) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[9
(1547911916687458304)]} 0 0
[junit4] 2> 1373770 INFO (qtp25213247-72676) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[9
(1547911916687458304)]} 0 0
[junit4] 2> 1373770 INFO (qtp16757665-72643) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[9 (1547911916687458304)]} 0 2
[junit4] 2> 1373773 INFO (qtp25213247-72678) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[10
(1547911916690604032)]} 0 0
[junit4] 2> 1373773 INFO (qtp4303715-72711) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[10
(1547911916690604032)]} 0 0
[junit4] 2> 1373773 INFO (qtp16757665-72650) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[10 (1547911916690604032)]} 0 1
[junit4] 2> 1373777 INFO (qtp4303715-72712) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[11
(1547911916694798336)]} 0 0
[junit4] 2> 1373777 INFO (qtp25213247-72677) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[11
(1547911916694798336)]} 0 0
[junit4] 2> 1373777 INFO (qtp16757665-72646) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[11 (1547911916694798336)]} 0 2
[junit4] 2> 1373780 INFO (qtp25213247-72672) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[12
(1547911916698992640)]} 0 0
[junit4] 2> 1373780 INFO (qtp4303715-72710) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[12
(1547911916698992640)]} 0 0
[junit4] 2> 1373780 INFO (qtp16757665-72645) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[12 (1547911916698992640)]} 0 1
[junit4] 2> 1373784 INFO (qtp25213247-72676) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[13
(1547911916702138368)]} 0 0
[junit4] 2> 1373784 INFO (qtp4303715-72706) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[13
(1547911916702138368)]} 0 0
[junit4] 2> 1373784 INFO (qtp16757665-72643) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[13 (1547911916702138368)]} 0 1
[junit4] 2> 1373788 INFO (qtp25213247-72678) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[14
(1547911916706332672)]} 0 0
[junit4] 2> 1373788 INFO (qtp4303715-72711) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[14
(1547911916706332672)]} 0 0
[junit4] 2> 1373788 INFO (qtp16757665-72650) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[14 (1547911916706332672)]} 0 1
[junit4] 2> 1373791 INFO (qtp4303715-72712) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[15
(1547911916709478400)]} 0 0
[junit4] 2> 1373791 INFO (qtp25213247-72677) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[15
(1547911916709478400)]} 0 0
[junit4] 2> 1373791 INFO (qtp16757665-72646) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[15 (1547911916709478400)]} 0 2
[junit4] 2> 1373794 INFO (qtp25213247-72672) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[16
(1547911916713672704)]} 0 0
[junit4] 2> 1373794 INFO (qtp4303715-72710) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[16
(1547911916713672704)]} 0 0
[junit4] 2> 1373794 INFO (qtp16757665-72645) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[16 (1547911916713672704)]} 0 1
[junit4] 2> 1373798 INFO (qtp25213247-72676) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[17
(1547911916717867008)]} 0 0
[junit4] 2> 1373799 INFO (qtp4303715-72706) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[17
(1547911916717867008)]} 0 0
[junit4] 2> 1373799 INFO (qtp16757665-72643) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[17 (1547911916717867008)]} 0 2
[junit4] 2> 1373802 INFO (qtp4303715-72711) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[18
(1547911916721012736)]} 0 0
[junit4] 2> 1373802 INFO (qtp25213247-72678) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[18
(1547911916721012736)]} 0 0
[junit4] 2> 1373802 INFO (qtp16757665-72650) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[18 (1547911916721012736)]} 0 1
[junit4] 2> 1373805 INFO (qtp4303715-72712) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[19
(1547911916724158464)]} 0 0
[junit4] 2> 1373805 INFO (qtp25213247-72677) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[19
(1547911916724158464)]} 0 0
[junit4] 2> 1373805 INFO (qtp16757665-72646) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[19 (1547911916724158464)]} 0 1
[junit4] 2> 1373809 INFO (qtp4303715-72710) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[20
(1547911916728352768)]} 0 0
[junit4] 2> 1373809 INFO (qtp25213247-72672) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[20
(1547911916728352768)]} 0 0
[junit4] 2> 1373809 INFO (qtp16757665-72645) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[20 (1547911916728352768)]} 0 2
[junit4] 2> 1373812 INFO (qtp25213247-72676) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[21
(1547911916731498496)]} 0 0
[junit4] 2> 1373812 INFO (qtp4303715-72706) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[21
(1547911916731498496)]} 0 0
[junit4] 2> 1373812 INFO (qtp16757665-72643) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[21 (1547911916731498496)]} 0 2
[junit4] 2> 1373815 INFO (qtp25213247-72678) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[22
(1547911916735692800)]} 0 0
[junit4] 2> 1373815 INFO (qtp4303715-72711) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[22
(1547911916735692800)]} 0 0
[junit4] 2> 1373816 INFO (qtp16757665-72650) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[22 (1547911916735692800)]} 0 1
[junit4] 2> 1373819 INFO (qtp4303715-72712) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[23
(1547911916738838528)]} 0 0
[junit4] 2> 1373819 INFO (qtp25213247-72677) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[23
(1547911916738838528)]} 0 0
[junit4] 2> 1373819 INFO (qtp16757665-72646) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[23 (1547911916738838528)]} 0 1
[junit4] 2> 1373822 INFO (qtp4303715-72710) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[24
(1547911916743032832)]} 0 0
[junit4] 2> 1373822 INFO (qtp25213247-72672) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[24
(1547911916743032832)]} 0 0
[junit4] 2> 1373823 INFO (qtp16757665-72645) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[24 (1547911916743032832)]} 0 1
[junit4] 2> 1373826 INFO (qtp25213247-72676) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[25
(1547911916746178560)]} 0 0
[junit4] 2> 1373826 INFO (qtp4303715-72706) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[25
(1547911916746178560)]} 0 0
[junit4] 2> 1373826 INFO (qtp16757665-72643) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[25 (1547911916746178560)]} 0 1
[junit4] 2> 1373829 INFO (qtp25213247-72678) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[26
(1547911916749324288)]} 0 0
[junit4] 2> 1373829 INFO (qtp4303715-72711) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[26
(1547911916749324288)]} 0 0
[junit4] 2> 1373829 INFO (qtp16757665-72650) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[26 (1547911916749324288)]} 0 1
[junit4] 2> 1373833 INFO (qtp25213247-72677) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[27
(1547911916753518592)]} 0 0
[junit4] 2> 1373833 INFO (qtp4303715-72712) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[27
(1547911916753518592)]} 0 0
[junit4] 2> 1373833 INFO (qtp16757665-72646) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[27 (1547911916753518592)]} 0 2
[junit4] 2> 1373836 INFO (qtp25213247-72672) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[28
(1547911916757712896)]} 0 0
[junit4] 2> 1373836 INFO (qtp4303715-72710) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[28
(1547911916757712896)]} 0 0
[junit4] 2> 1373837 INFO (qtp16757665-72645) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[28 (1547911916757712896)]} 0 1
[junit4] 2> 1373840 INFO (qtp25213247-72676) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[29
(1547911916760858624)]} 0 0
[junit4] 2> 1373840 INFO (qtp4303715-72706) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[29
(1547911916760858624)]} 0 0
[junit4] 2> 1373840 INFO (qtp16757665-72643) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={wt=javabin&version=2}{add=[29 (1547911916760858624)]} 0 1
[junit4] 2> 1373843 INFO (qtp25213247-72678) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/zn_ym/o path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:36254/zn_ym/o/collection1/&wt=javabin&version=2}{add=[30
(1547911916765052928)]} 0 0
[junit4] 2> 1373843 INFO (qtp4303715-72711) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [coll
[...truncated too long message...]
417984 INFO (TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@11ee33c{/zn_ym/o,null,UNAVAILABLE}
[junit4] 2> 1417985 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ChaosMonkey monkey: stop shard! 44421
[junit4] 2> 1417985 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=13181690
[junit4] 2> 1417986 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.Overseer Overseer
(id=96744494093107220-127.0.0.1:44421_zn_ym%2Fo-n_0000000005) closing
[junit4] 2> 1417986 INFO
(OverseerStateUpdate-96744494093107220-127.0.0.1:44421_zn_ym%2Fo-n_0000000005)
[ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:44421_zn_ym%2Fo
[junit4] 2> 1418984 WARN (zkCallback-18836-thread-3) [ ]
o.a.s.c.SyncStrategy Closed, skipping sync up.
[junit4] 2> 1418985 INFO (zkCallback-18836-thread-3) [ ]
o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@cda095
[junit4] 2> 1418985 INFO (zkCallback-18836-thread-3) [ ]
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
[junit4] 2> 1418985 INFO (zkCallback-18836-thread-3) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@5f3465
[junit4] 2> 1419487 WARN (zkCallback-18836-thread-2) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1419488 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.ServerConnector Stopped
ServerConnector@1dcbf23{HTTP/1.1,[http/1.1]}{127.0.0.1:44421}
[junit4] 2> 1419488 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@e5028f{/zn_ym/o,null,UNAVAILABLE}
[junit4] 2> 1419488 INFO
(TEST-PeerSyncReplicationTest.test-seed#[EBEDB14F5CD8BAE7]) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:41089 41089
[junit4] 2> 1419643 INFO (Thread-2660) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:41089 41089
[junit4] 2> 1419644 WARN (Thread-2660) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 6 /solr/security.json
[junit4] 2> 6 /solr/configs/conf1
[junit4] 2> 5 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2> 2
/solr/collections/collection1/leader_elect/shard1/election/96744494093107208-core_node1-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/96744494093107204-127.0.0.1:35467_zn_ym%2Fo-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/96744494093107208-127.0.0.1:36254_zn_ym%2Fo-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 53 /solr/overseer/collection-queue-work
[junit4] 2> 28 /solr/overseer/queue
[junit4] 2> 7 /solr/collections
[junit4] 2> 7 /solr/overseer/queue-work
[junit4] 2> 5 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=PeerSyncReplicationTest -Dtests.method=test
-Dtests.seed=EBEDB14F5CD8BAE7 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=uz -Dtests.timezone=America/Toronto -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 56.6s J2 | PeerSyncReplicationTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<204> but
was:<190>
[junit4] > at
__randomizedtesting.SeedInfo.seed([EBEDB14F5CD8BAE7:63B98E95F224D71F]:0)
[junit4] > at
org.apache.solr.cloud.PeerSyncReplicationTest.bringUpDeadNodeAndEnsureNoReplication(PeerSyncReplicationTest.java:280)
[junit4] > at
org.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:157)
[junit4] > at
jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native
Method)
[junit4] > at
jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
[junit4] > at
jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
[junit4] > at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
[junit4] 2> 1419647 INFO
(SUITE-PeerSyncReplicationTest-seed#[EBEDB14F5CD8BAE7]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_EBEDB14F5CD8BAE7-001
[junit4] 2> Oct 11, 2016 4:38:04 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene62,
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=uz,
timezone=America/Toronto
[junit4] 2> NOTE: Linux 4.4.0-36-generic i386/Oracle Corporation 9-ea
(32-bit)/cpus=12,threads=1,free=263955168,total=508878848
[junit4] 2> NOTE: All tests run in this JVM: [TestWriterPerf,
TestCollationField, OverseerStatusTest, TestSerializedLuceneMatchVersion,
TestReloadAndDeleteDocs, RecoveryAfterSoftCommitTest, HardAutoCommitTest,
HdfsSyncSliceTest, SegmentsInfoRequestHandlerTest,
DistributedExpandComponentTest, TestDynamicFieldResource, MinimalSchemaTest,
BasicDistributedZk2Test, TestSubQueryTransformerDistrib,
AnalyticsMergeStrategyTest, TestRecovery, CloneFieldUpdateProcessorFactoryTest,
TestDistributedMissingSort, DateRangeFieldTest, HdfsLockFactoryTest,
CoreAdminHandlerTest, DeleteInactiveReplicaTest,
OpenExchangeRatesOrgProviderTest, TestExceedMaxTermLength, TestReload,
HdfsBasicDistributedZk2Test, SolrTestCaseJ4Test, SpatialRPTFieldTypeTest,
TestCoreDiscovery, TestFieldCacheSortRandom, TestXIncludeConfig,
HdfsRecoverLeaseTest, TestCloudPivotFacet, TestStressLiveNodes,
DebugComponentTest, TestConfigSets, CircularListTest, TestSortByMinMaxFunction,
PreAnalyzedFieldTest, TestInitQParser, HLLSerializationTest,
TestInfoStreamLogging, TestUniqueKeyFieldResource, TestRawTransformer,
TestSolrFieldCacheMBean, TestDFRSimilarityFactory, TestAddFieldRealTimeGet,
TestChildDocTransformer, TestIndexSearcher, UnloadDistributedZkTest,
ParsingFieldUpdateProcessorsTest, FieldMutatingUpdateProcessorTest,
DistribCursorPagingTest, SSLMigrationTest, SimpleFacetsTest, PluginInfoTest,
SOLR749Test, TestExpandComponent, TestSolrJ, MultiThreadedOCPTest,
DirectUpdateHandlerTest, UpdateParamsTest, TestManagedSchema,
DocValuesMultiTest, CurrencyFieldXmlFileTest,
LeaderInitiatedRecoveryOnCommitTest, HdfsDirectoryFactoryTest,
CdcrRequestHandlerTest, TestDistribIDF, TestCryptoKeys, TestZkChroot,
TestStressReorder, XmlUpdateRequestHandlerTest,
DistributedFacetPivotLongTailTest, TestCopyFieldCollectionResource,
TimeZoneUtilsTest, TestJsonFacets, PingRequestHandlerTest, TestSchemaResource,
RecoveryZkTest, TestComplexPhraseQParserPlugin, TestSolrDynamicMBean,
SuggesterTSTTest, TestGeoJSONResponseWriter, JSONWriterTest,
TestFilteredDocIdSet, TolerantUpdateProcessorTest, CoreSorterTest,
TestLFUCache, TestPostingsSolrHighlighter, TestRestoreCore, TestBinaryField,
BadComponentTest, TestNoOpRegenerator, TestConfig,
TestSolrConfigHandlerConcurrent, SolrCoreCheckLockOnStartupTest,
TestTolerantSearch, CdcrBootstrapTest, DOMUtilTest,
TestExclusionRuleCollectionAccess, RequestHandlersTest, SyncSliceTest,
LeaderElectionIntegrationTest, BasicZkTest, TestReplicationHandler,
TestRandomDVFaceting, ZkCLITest, TestDistributedSearch, SolrCmdDistributorTest,
BadIndexSchemaTest, TestSort, TestLazyCores, TestBadConfig, HighlighterTest,
SoftAutoCommitTest, ShowFileRequestHandlerTest,
DistributedQueryElevationComponentTest, SolrIndexSplitterTest,
AnalysisAfterCoreReloadTest, SuggesterFSTTest, SuggesterTest,
SuggesterWFSTTest, PolyFieldTest, NoCacheHeaderTest,
WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest,
TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, QueryEqualityTest,
DirectUpdateHandlerOptimizeTest, SortByFunctionTest, TestRemoteStreaming,
SolrInfoMBeanTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest,
TestReversedWildcardFilterFactory, TestQueryTypes, FileBasedSpellCheckerTest,
MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest,
IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, TestJmxIntegration,
MBeansHandlerTest, TestAnalyzedSuggestions, TestBinaryResponseWriter,
TestQuerySenderListener, TestQuerySenderNoQuery, ResponseLogComponentTest,
TestMergePolicyConfig, TestDocSet, TestElisionMultitermQuery,
TestPhraseSuggestions, TestPerFieldSimilarity, TestIBSimilarityFactory,
TestJmxMonitoredMap, TestFastLRUCache, TestSuggestSpellingConverter,
TestLRUCache, UUIDFieldTest, CursorPagingTest, ActionThrottleTest, AssignTest,
CdcrReplicationHandlerTest, CleanupOldIndexTest, CollectionStateFormat2Test,
ConnectionManagerTest, DistribDocExpirationUpdateProcessorTest,
DistributedQueueTest, LeaderInitiatedRecoveryOnShardRestartTest,
MigrateRouteKeyTest, OverseerModifyCollectionTest, OverseerTaskQueueTest,
PeerSyncReplicationTest]
[junit4] Completed [479/637 (1!)] on J2 in 56.83s, 1 test, 1 failure <<<
FAILURES!
[...truncated 50435 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
