Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/2673/ Java: 32bit/jdk-9-ea+152 -server -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.cloud.PeerSyncReplicationTest.test Error Message: PeerSynced node did not become leader expected:<CloudJettyRunner [url=http://127.0.0.1:41535/collection1]> but was:<CloudJettyRunner [url=http://127.0.0.1:34976/collection1]> Stack Trace: java.lang.AssertionError: PeerSynced node did not become leader expected:<CloudJettyRunner [url=http://127.0.0.1:41535/collection1]> but was:<CloudJettyRunner [url=http://127.0.0.1:34976/collection1]> at __randomizedtesting.SeedInfo.seed([2E1321E509286F22:A6471E3FA7D402DA]: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.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:162) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:543) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at 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.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 11842 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/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/init-core-data-001 [junit4] 2> 1339553 INFO (SUITE-PeerSyncReplicationTest-seed#[2E1321E509286F22]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 1339553 INFO (SUITE-PeerSyncReplicationTest-seed#[2E1321E509286F22]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1339555 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1339555 INFO (Thread-1879) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1339555 INFO (Thread-1879) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1339655 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ZkTestServer start zk server on port:37787 [junit4] 2> 1339661 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] 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> 1339664 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] 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> 1339666 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] 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> 1339668 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] 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> 1339670 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] 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> 1339673 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] 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> 1339675 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] 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> 1339677 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] 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> 1339679 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] 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> 1339681 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] 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> 1339683 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] 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> 1340391 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/control-001/cores/collection1 [junit4] 2> 1340397 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1340397 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@89cd21{/,null,AVAILABLE} [junit4] 2> 1340398 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4328fc{HTTP/1.1,[http/1.1]}{127.0.0.1:37769} [junit4] 2> 1340398 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.Server Started @1342096ms [junit4] 2> 1340398 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/tempDir-001/control/data, hostPort=37769, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/control-001/cores} [junit4] 2> 1340398 ERROR (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1340398 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.5.0 [junit4] 2> 1340398 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1340398 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1340399 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-15T17:40:13.943989Z [junit4] 2> 1340401 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1340401 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/control-001/solr.xml [junit4] 2> 1340415 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1340417 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37787/solr [junit4] 2> 1340426 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37769_ [junit4] 2> 1340426 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.Overseer Overseer (id=97288323930718212-127.0.0.1:37769_-n_0000000000) starting [junit4] 2> 1340430 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37769_ [junit4] 2> 1340430 INFO (zkCallback-1179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1340560 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/control-001/cores [junit4] 2> 1340560 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1340561 INFO (OverseerStateUpdate-97288323930718212-127.0.0.1:37769_-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1341568 WARN (coreLoadExecutor-3728-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 1341569 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0 [junit4] 2> 1341577 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1341644 WARN (coreLoadExecutor-3728-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> 1341647 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1341652 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1341653 INFO (coreLoadExecutor-3728-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/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/control-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/control-001/cores/collection1/data/] [junit4] 2> 1341653 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6c74ff [junit4] 2> 1341660 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=16.70703125, floorSegmentMB=0.701171875, forceMergeDeletesPctAllowed=19.212125145610173, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5654203523547483 [junit4] 2> 1341680 WARN (coreLoadExecutor-3728-thread-1) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1341689 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1341689 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1341690 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1341690 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1341691 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1341693 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@fde466[collection1] main] [junit4] 2> 1341693 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1341693 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1341693 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1341695 INFO (searcherExecutor-3729-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@fde466[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1341696 INFO (coreLoadExecutor-3728-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1556613185133346816 [junit4] 2> 1341700 INFO (coreZkRegister-3721-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1341700 INFO (coreZkRegister-3721-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1341700 INFO (coreZkRegister-3721-thread-1) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37769/collection1/ [junit4] 2> 1341700 INFO (coreZkRegister-3721-thread-1) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1341700 INFO (coreZkRegister-3721-thread-1) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:37769/collection1/ has no replicas [junit4] 2> 1341702 INFO (coreZkRegister-3721-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37769/collection1/ shard1 [junit4] 2> 1341852 INFO (coreZkRegister-3721-thread-1) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1341907 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1341907 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37787/solr ready [junit4] 2> 1341907 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1342695 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-1-001/cores/collection1 [junit4] 2> 1342696 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-1-001 [junit4] 2> 1342697 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1342698 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@129832f{/,null,AVAILABLE} [junit4] 2> 1342698 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@cd40c6{HTTP/1.1,[http/1.1]}{127.0.0.1:34976} [junit4] 2> 1342698 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.Server Started @1344396ms [junit4] 2> 1342699 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/tempDir-001/jetty1, hostPort=34976, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-1-001/cores} [junit4] 2> 1342699 ERROR (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1342699 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.5.0 [junit4] 2> 1342699 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1342699 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1342699 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-15T17:40:16.244566Z [junit4] 2> 1342701 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1342701 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-1-001/solr.xml [junit4] 2> 1342708 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1342709 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37787/solr [junit4] 2> 1342714 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1342715 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34976_ [junit4] 2> 1342716 INFO (zkCallback-1179-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1342716 INFO (zkCallback-1183-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1342716 INFO (zkCallback-1188-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1342811 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-1-001/cores [junit4] 2> 1342811 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1342823 INFO (OverseerStateUpdate-97288323930718212-127.0.0.1:37769_-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1343831 WARN (coreLoadExecutor-3739-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 1343831 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0 [junit4] 2> 1343841 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1343926 WARN (coreLoadExecutor-3739-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> 1343928 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1343934 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1343935 INFO (coreLoadExecutor-3739-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/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-1-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-1-001/cores/collection1/data/] [junit4] 2> 1343935 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6c74ff [junit4] 2> 1343941 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=16.70703125, floorSegmentMB=0.701171875, forceMergeDeletesPctAllowed=19.212125145610173, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5654203523547483 [junit4] 2> 1343960 WARN (coreLoadExecutor-3739-thread-1) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1343976 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1343976 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1343979 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1343979 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1343981 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1343984 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@89a64a[collection1] main] [junit4] 2> 1343985 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1343985 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1343985 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1343986 INFO (searcherExecutor-3740-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@89a64a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1343988 INFO (coreLoadExecutor-3739-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1556613187536683008 [junit4] 2> 1343993 INFO (coreZkRegister-3734-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1343993 INFO (coreZkRegister-3734-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1343993 INFO (coreZkRegister-3734-thread-1) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34976/collection1/ [junit4] 2> 1343993 INFO (coreZkRegister-3734-thread-1) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1343993 INFO (coreZkRegister-3734-thread-1) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:34976/collection1/ has no replicas [junit4] 2> 1343994 INFO (coreZkRegister-3734-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:34976/collection1/ shard1 [junit4] 2> 1344145 INFO (coreZkRegister-3734-thread-1) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1345012 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-2-001/cores/collection1 [junit4] 2> 1345014 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-2-001 [junit4] 2> 1345014 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1345015 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@27e7f4{/,null,AVAILABLE} [junit4] 2> 1345015 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1b8171c{HTTP/1.1,[http/1.1]}{127.0.0.1:41535} [junit4] 2> 1345016 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.Server Started @1346713ms [junit4] 2> 1345016 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/tempDir-001/jetty2, hostPort=41535, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-2-001/cores} [junit4] 2> 1345016 ERROR (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1345016 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.5.0 [junit4] 2> 1345016 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1345016 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1345016 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-15T17:40:18.561665Z [junit4] 2> 1345019 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1345019 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-2-001/solr.xml [junit4] 2> 1345025 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1345026 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37787/solr [junit4] 2> 1345032 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1345033 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41535_ [junit4] 2> 1345034 INFO (zkCallback-1179-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1345035 INFO (zkCallback-1194-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1345035 INFO (zkCallback-1183-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1345034 INFO (zkCallback-1188-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1345139 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-2-001/cores [junit4] 2> 1345139 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1345140 INFO (OverseerStateUpdate-97288323930718212-127.0.0.1:37769_-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1346147 WARN (coreLoadExecutor-3750-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 1346148 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0 [junit4] 2> 1346167 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1346240 WARN (coreLoadExecutor-3750-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> 1346242 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1346247 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1346248 INFO (coreLoadExecutor-3750-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/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-2-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-2-001/cores/collection1/data/] [junit4] 2> 1346248 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6c74ff [junit4] 2> 1346254 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=16.70703125, floorSegmentMB=0.701171875, forceMergeDeletesPctAllowed=19.212125145610173, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5654203523547483 [junit4] 2> 1346296 WARN (coreLoadExecutor-3750-thread-1) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1346306 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1346306 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1346308 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1346308 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1346309 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1346311 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3088d5[collection1] main] [junit4] 2> 1346311 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1346312 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1346312 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1346313 INFO (searcherExecutor-3751-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3088d5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1346314 INFO (coreLoadExecutor-3750-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1556613189975670784 [junit4] 2> 1346317 INFO (coreZkRegister-3745-thread-1) [ ] o.a.s.c.ZkController Core needs to recover:collection1 [junit4] 2> 1346317 INFO (updateExecutor-1191-thread-1) [ ] o.a.s.u.DefaultSolrCoreState Running recovery [junit4] 2> 1346317 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true [junit4] 2> 1346318 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]] [junit4] 2> 1346318 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1] [junit4] 2> 1346318 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null} [junit4] 2> 1346318 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [http://127.0.0.1:34976/collection1/] and I am [http://127.0.0.1:41535/collection1/] [junit4] 2> 1346320 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.c.RecoveryStrategy Sending prep recovery command to [http://127.0.0.1:34976]; [WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:41535_&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true] [junit4] 2> 1346322 INFO (qtp22845391-7630) [ ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true [junit4] 2> 1346322 INFO (qtp22845391-7630) [ ] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 (shard1 of collection1) have state: recovering [junit4] 2> 1346322 INFO (qtp22845391-7630) [ ] 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:41535_, coreNodeName=core_node2, onlyIfActiveCheckResult=false, nodeProps: core_node2:{"core":"collection1","base_url":"http://127.0.0.1:41535","node_name":"127.0.0.1:41535_","state":"down"} [junit4] 2> 1347295 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-3-001/cores/collection1 [junit4] 2> 1347296 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-3-001 [junit4] 2> 1347297 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1347298 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@8e9340{/,null,AVAILABLE} [junit4] 2> 1347299 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1fbe268{HTTP/1.1,[http/1.1]}{127.0.0.1:39655} [junit4] 2> 1347299 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.Server Started @1348997ms [junit4] 2> 1347299 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/tempDir-001/jetty3, hostPort=39655, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-3-001/cores} [junit4] 2> 1347299 ERROR (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1347300 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.5.0 [junit4] 2> 1347300 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1347300 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1347300 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-15T17:40:20.845287Z [junit4] 2> 1347304 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1347304 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-3-001/solr.xml [junit4] 2> 1347312 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1347315 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37787/solr [junit4] 2> 1347321 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1347322 INFO (qtp22845391-7630) [ ] 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:41535_, coreNodeName=core_node2, onlyIfActiveCheckResult=false, nodeProps: core_node2:{"core":"collection1","base_url":"http://127.0.0.1:41535","node_name":"127.0.0.1:41535_","state":"recovering"} [junit4] 2> 1347322 INFO (qtp22845391-7630) [ ] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds. [junit4] 2> 1347323 INFO (qtp22845391-7630) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:41535_&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> 1347323 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39655_ [junit4] 2> 1347324 INFO (zkCallback-1194-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1347324 INFO (zkCallback-1188-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1347324 INFO (zkCallback-1183-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1347324 INFO (zkCallback-1201-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1347324 INFO (zkCallback-1179-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1347396 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-3-001/cores [junit4] 2> 1347396 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 1347399 INFO (OverseerStateUpdate-97288323930718212-127.0.0.1:37769_-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1348406 WARN (coreLoadExecutor-3761-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 1348407 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0 [junit4] 2> 1348425 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1348486 WARN (coreLoadExecutor-3761-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> 1348488 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 1348493 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1348495 INFO (coreLoadExecutor-3761-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/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-3-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001/shard-3-001/cores/collection1/data/] [junit4] 2> 1348496 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6c74ff [junit4] 2> 1348502 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=16.70703125, floorSegmentMB=0.701171875, forceMergeDeletesPctAllowed=19.212125145610173, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5654203523547483 [junit4] 2> 1348529 WARN (coreLoadExecutor-3761-thread-1) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1348541 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1348541 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1348557 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1348557 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1348558 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1348560 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b6f03[collection1] main] [junit4] 2> 1348561 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1348561 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1348561 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1348562 INFO (searcherExecutor-3762-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7b6f03[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1348564 INFO (coreLoadExecutor-3761-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1556613192334966784 [junit4] 2> 1348567 INFO (coreZkRegister-3756-thread-1) [ ] o.a.s.c.ZkController Core needs to recover:collection1 [junit4] 2> 1348567 INFO (updateExecutor-1198-thread-1) [ ] o.a.s.u.DefaultSolrCoreState Running recovery [junit4] 2> 1348567 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true [junit4] 2> 1348568 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]] [junit4] 2> 1348568 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1] [junit4] 2> 1348568 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null} [junit4] 2> 1348568 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [http://127.0.0.1:34976/collection1/] and I am [http://127.0.0.1:39655/collection1/] [junit4] 2> 1348570 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.c.RecoveryStrategy Sending prep recovery command to [http://127.0.0.1:34976]; [WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:39655_&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true] [junit4] 2> 1348571 INFO (qtp22845391-7630) [ ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true [junit4] 2> 1348571 INFO (qtp22845391-7630) [ ] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 (shard1 of collection1) have state: recovering [junit4] 2> 1348571 INFO (qtp22845391-7630) [ ] 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:39655_, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"http://127.0.0.1:39655","node_name":"127.0.0.1:39655_","state":"down"} [junit4] 2> 1348746 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 1348746 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Wait for recoveries to finish - wait 30 for each attempt [junit4] 2> 1348746 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):30 [junit4] 2> 1349571 INFO (qtp22845391-7630) [ ] 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:39655_, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"http://127.0.0.1:39655","node_name":"127.0.0.1:39655_","state":"recovering"} [junit4] 2> 1349571 INFO (qtp22845391-7630) [ ] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds. [junit4] 2> 1349571 INFO (qtp22845391-7630) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:39655_&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> 1354323 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.c.RecoveryStrategy Attempting to PeerSync from [http://127.0.0.1:34976/collection1/] - recoveringAfterStartup=[true] [junit4] 2> 1354323 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.u.PeerSync PeerSync: core=collection1 url=http://127.0.0.1:41535 START replicas=[http://127.0.0.1:34976/collection1/] nUpdates=1000 [junit4] 2> 1354326 INFO (qtp22845391-7630) [ ] 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> 1354326 INFO (qtp22845391-7630) [ ] o.a.s.c.S.Request [collection1] webapp= path=/get params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1354327 INFO (recoveryExecutor-1192-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> 1354327 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync [junit4] 2> 1354327 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1354327 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 1354327 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1354327 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful. [junit4] 2> 1354327 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync. [junit4] 2> 1354328 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.c.RecoveryStrategy No replay needed. [junit4] 2> 1354328 INFO (recoveryExecutor-1192-thread-1) [ ] o.a.s.c.RecoveryStrategy Registering as Active after recovery. [junit4] 2> 1356572 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.c.RecoveryStrategy Attempting to PeerSync from [http://127.0.0.1:34976/collection1/] - recoveringAfterStartup=[true] [junit4] 2> 1356572 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.u.PeerSync PeerSync: core=collection1 url=http://127.0.0.1:39655 START replicas=[http://127.0.0.1:34976/collection1/] nUpdates=1000 [junit4] 2> 1356574 INFO (qtp22845391-7627) [ ] 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> 1356574 INFO (qtp22845391-7627) [ ] o.a.s.c.S.Request [collection1] webapp= path=/get params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 1356575 INFO (recoveryExecutor-1199-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> 1356575 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync [junit4] 2> 1356575 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1356575 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 1356575 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1356575 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful. [junit4] 2> 1356575 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync. [junit4] 2> 1356575 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.c.RecoveryStrategy No replay needed. [junit4] 2> 1356576 INFO (recoveryExecutor-1199-thread-1) [ ] o.a.s.c.RecoveryStrategy Registering as Active after recovery. [junit4] 2> 1356747 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1 [junit4] 2> 1356748 INFO (qtp23563995-7596) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1356748 INFO (qtp23563995-7596) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 1356749 INFO (qtp23563995-7596) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1356749 INFO (qtp23563995-7596) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1 [junit4] 2> 1356751 INFO (qtp22845391-7627) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1356751 INFO (qtp22845391-7627) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 1356751 INFO (qtp10575326-7658) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1356752 INFO (qtp10575326-7658) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 1356752 INFO (qtp19808167-7692) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 1356752 INFO (qtp19808167-7692) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 1356753 INFO (qtp22845391-7627) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1356753 INFO (qtp22845391-7627) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34976/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1 [junit4] 2> 1356753 INFO (qtp19808167-7692) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1356753 INFO (qtp19808167-7692) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34976/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1 [junit4] 2> 1356754 INFO (qtp10575326-7658) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 1356754 INFO (qtp10575326-7658) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:34976/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2 [junit4] 2> 1356754 INFO (qtp22845391-7626) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 4 [junit4] 2> 1356755 INFO (qtp22845391-7632) [ ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 1356756 INFO (qtp10575326-7660) [ ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 1356757 INFO (qtp19808167-7694) [ ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 1358761 INFO (qtp23563995-7595) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1556613203025199104)} 0 2 [junit4] 2> 1358770 INFO (qtp10575326-7659) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&_version_=-1556613203029393408&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{deleteByQuery=*:* (-1556613203029393408)} 0 2 [junit4] 2> 1358770 INFO (qtp19808167-7693) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&_version_=-1556613203029393408&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{deleteByQuery=*:* (-1556613203029393408)} 0 2 [junit4] 2> 1358770 INFO (qtp22845391-7628) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1556613203029393408)} 0 6 [junit4] 2> 1358780 INFO (qtp10575326-7658) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[0 (1556613203039879168)]} 0 1 [junit4] 2> 1358780 INFO (qtp19808167-7692) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[0 (1556613203039879168)]} 0 2 [junit4] 2> 1358781 INFO (qtp22845391-7626) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[0 (1556613203039879168)]} 0 7 [junit4] 2> 1358784 INFO (qtp10575326-7654) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[1 (1556613203049316352)]} 0 0 [junit4] 2> 1358784 INFO (qtp19808167-7688) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[1 (1556613203049316352)]} 0 0 [junit4] 2> 1358784 INFO (qtp22845391-7625) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1556613203049316352)]} 0 2 [junit4] 2> 1358788 INFO (qtp10575326-7660) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[2 (1556613203053510656)]} 0 0 [junit4] 2> 1358788 INFO (qtp19808167-7694) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[2 (1556613203053510656)]} 0 0 [junit4] 2> 1358788 INFO (qtp22845391-7632) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1556613203053510656)]} 0 2 [junit4] 2> 1358791 INFO (qtp19808167-7693) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[3 (1556613203056656384)]} 0 0 [junit4] 2> 1358791 INFO (qtp10575326-7659) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[3 (1556613203056656384)]} 0 0 [junit4] 2> 1358791 INFO (qtp22845391-7628) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1556613203056656384)]} 0 1 [junit4] 2> 1358794 INFO (qtp10575326-7658) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[4 (1556613203060850688)]} 0 0 [junit4] 2> 1358794 INFO (qtp19808167-7692) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[4 (1556613203060850688)]} 0 0 [junit4] 2> 1358795 INFO (qtp22845391-7626) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1556613203060850688)]} 0 1 [junit4] 2> 1358798 INFO (qtp19808167-7688) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[5 (1556613203063996416)]} 0 0 [junit4] 2> 1358798 INFO (qtp10575326-7654) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[5 (1556613203063996416)]} 0 0 [junit4] 2> 1358804 INFO (qtp22845391-7625) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1556613203063996416)]} 0 8 [junit4] 2> 1358808 INFO (qtp10575326-7660) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[6 (1556613203074482176)]} 0 0 [junit4] 2> 1358808 INFO (qtp19808167-7694) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[6 (1556613203074482176)]} 0 0 [junit4] 2> 1358808 INFO (qtp22845391-7632) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1556613203074482176)]} 0 2 [junit4] 2> 1358812 INFO (qtp10575326-7659) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[7 (1556613203077627904)]} 0 0 [junit4] 2> 1358812 INFO (qtp19808167-7693) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[7 (1556613203077627904)]} 0 0 [junit4] 2> 1358812 INFO (qtp22845391-7628) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1556613203077627904)]} 0 2 [junit4] 2> 1358815 INFO (qtp10575326-7658) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[8 (1556613203081822208)]} 0 0 [junit4] 2> 1358815 INFO (qtp19808167-7692) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[8 (1556613203081822208)]} 0 0 [junit4] 2> 1358815 INFO (qtp22845391-7626) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1556613203081822208)]} 0 1 [junit4] 2> 1358819 INFO (qtp19808167-7688) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[9 (1556613203086016512)]} 0 0 [junit4] 2> 1358819 INFO (qtp10575326-7654) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[9 (1556613203086016512)]} 0 0 [junit4] 2> 1358819 INFO (qtp22845391-7625) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1556613203086016512)]} 0 1 [junit4] 2> 1358823 INFO (qtp10575326-7660) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[10 (1556613203090210816)]} 0 1 [junit4] 2> 1358823 INFO (qtp19808167-7694) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[10 (1556613203090210816)]} 0 1 [junit4] 2> 1358824 INFO (qtp22845391-7632) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1556613203090210816)]} 0 3 [junit4] 2> 1358827 INFO (qtp19808167-7693) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[11 (1556613203094405120)]} 0 0 [junit4] 2> 1358827 INFO (qtp10575326-7660) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[11 (1556613203094405120)]} 0 0 [junit4] 2> 1358827 INFO (qtp22845391-7628) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1556613203094405120)]} 0 1 [junit4] 2> 1358830 INFO (qtp19808167-7692) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[12 (1556613203098599424)]} 0 0 [junit4] 2> 1358830 INFO (qtp10575326-7658) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[12 (1556613203098599424)]} 0 0 [junit4] 2> 1358831 INFO (qtp22845391-7626) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1556613203098599424)]} 0 1 [junit4] 2> 1358834 INFO (qtp19808167-7688) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[13 (1556613203101745152)]} 0 0 [junit4] 2> 1358834 INFO (qtp10575326-7654) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[13 (1556613203101745152)]} 0 0 [junit4] 2> 1358834 INFO (qtp22845391-7625) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1556613203101745152)]} 0 1 [junit4] 2> 1358837 INFO (qtp10575326-7659) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[14 (1556613203104890880)]} 0 0 [junit4] 2> 1358837 INFO (qtp19808167-7694) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[14 (1556613203104890880)]} 0 0 [junit4] 2> 1358837 INFO (qtp22845391-7632) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1556613203104890880)]} 0 1 [junit4] 2> 1358841 INFO (qtp19808167-7693) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[15 (1556613203109085184)]} 0 0 [junit4] 2> 1358841 INFO (qtp10575326-7659) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[15 (1556613203109085184)]} 0 0 [junit4] 2> 1358841 INFO (qtp22845391-7628) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1556613203109085184)]} 0 2 [junit4] 2> 1358844 INFO (qtp19808167-7692) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[16 (1556613203112230912)]} 0 0 [junit4] 2> 1358844 INFO (qtp10575326-7658) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[16 (1556613203112230912)]} 0 0 [junit4] 2> 1358844 INFO (qtp22845391-7626) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1556613203112230912)]} 0 1 [junit4] 2> 1358847 INFO (qtp19808167-7688) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[17 (1556613203116425216)]} 0 0 [junit4] 2> 1358847 INFO (qtp10575326-7654) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[17 (1556613203116425216)]} 0 0 [junit4] 2> 1358848 INFO (qtp22845391-7625) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1556613203116425216)]} 0 1 [junit4] 2> 1358851 INFO (qtp19808167-7694) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[18 (1556613203119570944)]} 0 0 [junit4] 2> 1358851 INFO (qtp10575326-7660) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[18 (1556613203119570944)]} 0 0 [junit4] 2> 1358851 INFO (qtp22845391-7632) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[18 (1556613203119570944)]} 0 1 [junit4] 2> 1358854 INFO (qtp19808167-7693) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[19 (1556613203123765248)]} 0 0 [junit4] 2> 1358854 INFO (qtp10575326-7659) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[19 (1556613203123765248)]} 0 0 [junit4] 2> 1358855 INFO (qtp22845391-7628) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[19 (1556613203123765248)]} 0 2 [junit4] 2> 1358858 INFO (qtp19808167-7692) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[20 (1556613203126910976)]} 0 0 [junit4] 2> 1358858 INFO (qtp10575326-7658) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[20 (1556613203126910976)]} 0 0 [junit4] 2> 1358858 INFO (qtp22845391-7626) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[20 (1556613203126910976)]} 0 1 [junit4] 2> 1358862 INFO (qtp19808167-7688) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[21 (1556613203131105280)]} 0 0 [junit4] 2> 1358862 INFO (qtp10575326-7654) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[21 (1556613203131105280)]} 0 0 [junit4] 2> 1358862 INFO (qtp22845391-7625) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[21 (1556613203131105280)]} 0 1 [junit4] 2> 1358865 INFO (qtp19808167-7694) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[22 (1556613203135299584)]} 0 0 [junit4] 2> 1358866 INFO (qtp10575326-7660) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[22 (1556613203135299584)]} 0 0 [junit4] 2> 1358866 INFO (qtp22845391-7632) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[22 (1556613203135299584)]} 0 2 [junit4] 2> 1358869 INFO (qtp10575326-7659) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[23 (1556613203138445312)]} 0 0 [junit4] 2> 1358869 INFO (qtp19808167-7693) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[23 (1556613203138445312)]} 0 0 [junit4] 2> 1358869 INFO (qtp22845391-7628) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[23 (1556613203138445312)]} 0 1 [junit4] 2> 1358873 INFO (qtp19808167-7692) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[24 (1556613203142639616)]} 0 0 [junit4] 2> 1358873 INFO (qtp10575326-7658) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[24 (1556613203142639616)]} 0 0 [junit4] 2> 1358873 INFO (qtp22845391-7626) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[24 (1556613203142639616)]} 0 1 [junit4] 2> 1358876 INFO (qtp19808167-7688) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[25 (1556613203146833920)]} 0 0 [junit4] 2> 1358876 INFO (qtp10575326-7654) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[25 (1556613203146833920)]} 0 0 [junit4] 2> 1358876 INFO (qtp22845391-7625) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[25 (1556613203146833920)]} 0 1 [junit4] 2> 1358880 INFO (qtp19808167-7694) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[26 (1556613203149979648)]} 0 0 [junit4] 2> 1358880 INFO (qtp10575326-7660) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[26 (1556613203149979648)]} 0 0 [junit4] 2> 1358880 INFO (qtp22845391-7632) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[26 (1556613203149979648)]} 0 1 [junit4] 2> 1358883 INFO (qtp19808167-7693) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[27 (1556613203153125376)]} 0 0 [junit4] 2> 1358883 INFO (qtp10575326-7659) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[27 (1556613203153125376)]} 0 0 [junit4] 2> 1358883 INFO (qtp22845391-7628) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[27 (1556613203153125376)]} 0 2 [junit4] 2> 1358887 INFO (qtp19808167-7692) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[28 (1556613203157319680)]} 0 0 [junit4] 2> 1358887 INFO (qtp10575326-7658) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[28 (1556613203157319680)]} 0 0 [junit4] 2> 1358887 INFO (qtp22845391-7626) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[28 (1556613203157319680)]} 0 2 [junit4] 2> 1358890 INFO (qtp19808167-7688) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[29 (1556613203160465408)]} 0 0 [junit4] 2> 1358890 INFO (qtp10575326-7654) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[29 (1556613203160465408)]} 0 0 [junit4] 2> 1358890 INFO (qtp22845391-7625) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[29 (1556613203160465408)]} 0 1 [junit4] 2> 1358894 INFO (qtp19808167-7694) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[30 (1556613203164659712)]} 0 0 [junit4] 2> 1358894 INFO (qtp10575326-7660) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[30 (1556613203164659712)]} 0 0 [junit4] 2> 1358894 INFO (qtp22845391-7632) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[30 (1556613203164659712)]} 0 1 [junit4] 2> 1358897 INFO (qtp10575326-7659) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34976/collection1/&wt=javabin&version=2}{add=[31 (1556613203167805440)]} 0 0 [junit4] 2> 1358897 INFO (qtp19808167-7693) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={update.distrib=FROMLEADER&di [...truncated too long message...] java:844) [junit4] 2> 1380431 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 1380431 INFO (zkCallback-1208-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41535_ [junit4] 2> 1380432 INFO (zkCallback-1208-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 1380432 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4328fc{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 1380432 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@89cd21{/,null,UNAVAILABLE} [junit4] 2> 1380432 INFO (zkCallback-1208-thread-2) [ ] o.a.s.c.Overseer Overseer (id=97288323930718225-127.0.0.1:41535_-n_0000000004) starting [junit4] 2> 1380432 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ChaosMonkey monkey: stop shard! 34976 [junit4] 2> 1380432 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ChaosMonkey monkey: stop shard! 41535 [junit4] 2> 1380432 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=10589948 [junit4] 2> 1380436 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.Overseer Overseer (id=97288323930718225-127.0.0.1:41535_-n_0000000004) closing [junit4] 2> 1380436 INFO (OverseerStateUpdate-97288323930718225-127.0.0.1:41535_-n_0000000004) [ ] o.a.s.c.Overseer According to ZK I (id=97288323930718225-127.0.0.1:41535_-n_0000000004) am no longer a leader. [junit4] 2> 1380436 INFO (OverseerStateUpdate-97288323930718225-127.0.0.1:41535_-n_0000000004) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41535_ [junit4] 2> 1380436 INFO (OverseerCollectionConfigSetProcessor-97288323930718225-127.0.0.1:41535_-n_0000000004) [ ] o.a.s.c.OverseerTaskProcessor According to ZK I (id=97288323930718225-127.0.0.1:41535_-n_0000000004) am no longer a leader. [junit4] 2> 1381937 WARN (zkCallback-1208-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> 1382604 WARN (zkCallback-1208-thread-1) [ ] o.a.s.c.SyncStrategy Closed, skipping sync up. [junit4] 2> 1382604 INFO (zkCallback-1208-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1b1cbeb [junit4] 2> 1382949 INFO (zkCallback-1208-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 1382950 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 1382950 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@14b3fe3{HTTP/1.1,[http/1.1]}{127.0.0.1:41535} [junit4] 2> 1382950 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3bccde{/,null,UNAVAILABLE} [junit4] 2> 1382951 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ChaosMonkey monkey: stop shard! 39655 [junit4] 2> 1382951 INFO (TEST-PeerSyncReplicationTest.test-seed#[2E1321E509286F22]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37787 37787 [junit4] 2> 1387969 INFO (Thread-1879) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37787 37787 [junit4] 2> 1387969 WARN (Thread-1879) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/aliases.json [junit4] 2> 6 /solr/clusterprops.json [junit4] 2> 5 /solr/security.json [junit4] 2> 5 /solr/configs/conf1 [junit4] 2> 4 /solr/collections/collection1/state.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/97288323930718216-core_node1-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/97288323930718212-127.0.0.1:37769_-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/97288323930718216-127.0.0.1:34976_-n_0000000001 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 39 /solr/overseer/collection-queue-work [junit4] 2> 22 /solr/overseer/queue [junit4] 2> 6 /solr/collections [junit4] 2> 5 /solr/live_nodes [junit4] 2> 5 /solr/overseer/queue-work [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=PeerSyncReplicationTest -Dtests.method=test -Dtests.seed=2E1321E509286F22 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-SG -Dtests.timezone=Etc/GMT+8 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 48.4s J0 | PeerSyncReplicationTest.test <<< [junit4] > Throwable #1: java.lang.AssertionError: PeerSynced node did not become leader expected:<CloudJettyRunner [url=http://127.0.0.1:41535/collection1]> but was:<CloudJettyRunner [url=http://127.0.0.1:34976/collection1]> [junit4] > at __randomizedtesting.SeedInfo.seed([2E1321E509286F22:A6471E3FA7D402DA]:0) [junit4] > at org.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:162) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:543) [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.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1387973 INFO (SUITE-PeerSyncReplicationTest-seed#[2E1321E509286F22]-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/J0/temp/solr.cloud.PeerSyncReplicationTest_2E1321E509286F22-001 [junit4] 2> NOTE: test params are: codec=Lucene62, sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=en-SG, timezone=Etc/GMT+8 [junit4] 2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=84582072,total=273022976 [junit4] 2> NOTE: All tests run in this JVM: [TestQuerySenderNoQuery, SuggestComponentContextFilterQueryTest, TestSchemalessBufferedUpdates, TestImplicitCoreProperties, OverseerRolesTest, TestStandardQParsers, TestRangeQuery, ClusterStateTest, TestBadConfig, TestReplicaProperties, TestExpandComponent, TestSQLHandler, DefaultValueUpdateProcessorTest, TestRestManager, StandardRequestHandlerTest, TestAtomicUpdateErrorCases, TestPseudoReturnFields, TestStressLucene, HttpPartitionTest, DistributedFacetPivotLargeTest, CursorPagingTest, QueryResultKeyTest, ScriptEngineTest, TestDistribIDF, TestSolrConfigHandler, SynonymTokenizerTest, TestComplexPhraseLeadingWildcard, TestSolr4Spatial, OverriddenZkACLAndCredentialsProvidersTest, TestComponentsName, TestNumericTerms64, TestCSVLoader, TestIBSimilarityFactory, TestJsonFacets, MergeStrategyTest, TestNRTOpen, TestSolrCloudWithHadoopAuthPlugin, TestCrossCoreJoin, AlternateDirectoryTest, AnalysisAfterCoreReloadTest, TestCopyFieldCollectionResource, ReplaceNodeTest, TestOmitPositions, TestIntervalFaceting, TestFaceting, TestFileDictionaryLookup, TestPerFieldSimilarity, CurrencyFieldOpenExchangeTest, TestXmlQParser, SolrXmlInZkTest, TestFunctionQuery, PreAnalyzedUpdateProcessorTest, BasicZkTest, ChaosMonkeyNothingIsSafeTest, SignatureUpdateProcessorFactoryTest, CollectionTooManyReplicasTest, TestQueryWrapperFilter, UUIDFieldTest, TestLazyCores, TestCloudInspectUtil, TestLeaderElectionWithEmptyReplica, TestExceedMaxTermLength, QueryEqualityTest, TriLevelCompositeIdRoutingTest, DeleteLastCustomShardedReplicaTest, TestPerFieldSimilarityWithDefaultOverride, ResourceLoaderTest, TestCodecSupport, DistributedQueryComponentOptimizationTest, TestSerializedLuceneMatchVersion, TestSolrCLIRunExample, HdfsThreadLeakTest, FastVectorHighlighterTest, DistributedFacetPivotSmallTest, TestGraphMLResponseWriter, TestFastLRUCache, HdfsWriteToMultipleCollectionsTest, ZkStateReaderTest, RuleEngineTest, TestBinaryResponseWriter, HighlighterConfigTest, TestEmbeddedSolrServerConstructors, TestUniqueKeyFieldResource, HLLUtilTest, TestReplicationHandler, TestSizeLimitedDistributedMap, HdfsBasicDistributedZkTest, DistributedVersionInfoTest, TestJoin, SolrGangliaReporterTest, CoreMergeIndexesAdminHandlerTest, TestLocalFSCloudBackupRestore, TestRandomDVFaceting, ConjunctionSolrSpellCheckerTest, DateRangeFieldTest, AsyncCallRequestStatusResponseTest, TestRandomFlRTGCloud, ExternalFileFieldSortTest, TestNamedUpdateProcessors, CloudMLTQParserTest, SyncSliceTest, MBeansHandlerTest, TestSearcherReuse, TestDynamicFieldResource, TestRawResponseWriter, TestTolerantUpdateProcessorCloud, TestDFISimilarityFactory, PeerSyncReplicationTest] [junit4] Completed [344/680 (1!)] on J0 in 48.44s, 1 test, 1 failure <<< FAILURES! [...truncated 52669 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
