You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/01/12 21:45:01 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (64bit/jdk-9-ea+147) - Build
# 18761 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/18761/
Java: 64bit/jdk-9-ea+147 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.cloud.PeerSyncReplicationTest.test
Error Message:
PeerSynced node did not become leader expected:<CloudJettyRunner [url=https://127.0.0.1:43528/ry/collection1]> but was:<CloudJettyRunner [url=https://127.0.0.1:46110/ry/collection1]>
Stack Trace:
java.lang.AssertionError: PeerSynced node did not become leader expected:<CloudJettyRunner [url=https://127.0.0.1:43528/ry/collection1]> but was:<CloudJettyRunner [url=https://127.0.0.1:46110/ry/collection1]>
at __randomizedtesting.SeedInfo.seed([89BD0B901B2D8976:1E9344AB5D1E48E]: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:538)
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:985)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
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)
FAILED: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenRenew
Error Message:
expected:<200> but was:<403>
Stack Trace:
java.lang.AssertionError: expected:<200> but was:<403>
at __randomizedtesting.SeedInfo.seed([89BD0B901B2D8976:BE26FF8E23E154D2]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.renewDelegationToken(TestSolrCloudWithDelegationTokens.java:130)
at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.verifyDelegationTokenRenew(TestSolrCloudWithDelegationTokens.java:315)
at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenRenew(TestSolrCloudWithDelegationTokens.java:332)
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:538)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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 11824 lines...]
[junit4] Suite: org.apache.solr.cloud.PeerSyncReplicationTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/init-core-data-001
[junit4] 2> 1043798 INFO (SUITE-PeerSyncReplicationTest-seed#[89BD0B901B2D8976]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1043801 INFO (SUITE-PeerSyncReplicationTest-seed#[89BD0B901B2D8976]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ry/
[junit4] 2> 1043803 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1043803 INFO (Thread-1205) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1043803 INFO (Thread-1205) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1043903 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.ZkTestServer start zk server on port:39981
[junit4] 2> 1043910 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1043912 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1043913 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1043916 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1043917 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1043919 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1043921 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1043922 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1043924 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1043926 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1043928 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1044248 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/control-001/cores/collection1
[junit4] 2> 1044252 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1044252 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4cb42bb1{/ry,null,AVAILABLE}
[junit4] 2> 1044255 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@57f217d9{SSL,[ssl, http/1.1]}{127.0.0.1:44637}
[junit4] 2> 1044255 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.Server Started @1046732ms
[junit4] 2> 1044255 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ry, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/tempDir-001/control/data, hostPort=44637, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/control-001/cores}
[junit4] 2> 1044255 ERROR (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1044255 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 1044255 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1044255 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1044255 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T21:18:52.472795Z
[junit4] 2> 1044265 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1044265 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/control-001/solr.xml
[junit4] 2> 1044271 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39981/solr
[junit4] 2> 1044280 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44637_ry
[junit4] 2> 1044280 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.Overseer Overseer (id=97272196760928261-127.0.0.1:44637_ry-n_0000000000) starting
[junit4] 2> 1044282 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44637_ry
[junit4] 2> 1044283 INFO (OverseerStateUpdate-97272196760928261-127.0.0.1:44637_ry-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1044310 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/control-001/cores
[junit4] 2> 1044310 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1044312 INFO (OverseerStateUpdate-97272196760928261-127.0.0.1:44637_ry-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1045318 WARN (coreLoadExecutor-2107-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1045319 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1045328 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1045388 WARN (coreLoadExecutor-2107-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> 1045389 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1045395 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 1045396 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/control-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/control-001/cores/collection1/data/]
[junit4] 2> 1045396 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d05de24
[junit4] 2> 1045398 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=13.0234375, floorSegmentMB=1.796875, forceMergeDeletesPctAllowed=11.803778042135761, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4022481367277221
[junit4] 2> 1045423 WARN (coreLoadExecutor-2107-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> 1045431 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1045431 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1045432 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1045432 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1045433 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1045433 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f32d5a8[collection1] main]
[junit4] 2> 1045434 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1045434 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1045434 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1045435 INFO (searcherExecutor-2108-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5f32d5a8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1045435 INFO (coreLoadExecutor-2107-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1556355149885079552
[junit4] 2> 1045440 INFO (coreZkRegister-2100-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1045440 INFO (coreZkRegister-2100-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1045440 INFO (coreZkRegister-2100-thread-1) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:44637/ry/collection1/
[junit4] 2> 1045440 INFO (coreZkRegister-2100-thread-1) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1045440 INFO (coreZkRegister-2100-thread-1) [ ] o.a.s.c.SyncStrategy https://127.0.0.1:44637/ry/collection1/ has no replicas
[junit4] 2> 1045442 INFO (coreZkRegister-2100-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:44637/ry/collection1/ shard1
[junit4] 2> 1045592 INFO (coreZkRegister-2100-thread-1) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1045872 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1045872 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39981/solr ready
[junit4] 2> 1045873 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1046198 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-1-001/cores/collection1
[junit4] 2> 1046199 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-1-001
[junit4] 2> 1046201 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1046201 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@286fec38{/ry,null,AVAILABLE}
[junit4] 2> 1046202 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@725c3013{SSL,[ssl, http/1.1]}{127.0.0.1:46110}
[junit4] 2> 1046202 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.Server Started @1048679ms
[junit4] 2> 1046202 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ry, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/tempDir-001/jetty1, hostPort=46110, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-1-001/cores}
[junit4] 2> 1046203 ERROR (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1046203 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 1046203 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1046203 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1046203 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T21:18:54.420550Z
[junit4] 2> 1046205 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1046205 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-1-001/solr.xml
[junit4] 2> 1046212 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39981/solr
[junit4] 2> 1046216 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1046218 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46110_ry
[junit4] 2> 1046219 INFO (zkCallback-793-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1046220 INFO (zkCallback-802-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1046220 INFO (zkCallback-797-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1046232 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-1-001/cores
[junit4] 2> 1046233 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1046234 INFO (OverseerStateUpdate-97272196760928261-127.0.0.1:44637_ry-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1047240 WARN (coreLoadExecutor-2118-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1047241 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1047249 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1047315 WARN (coreLoadExecutor-2118-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> 1047316 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1047321 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1047322 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-1-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 1047322 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d05de24
[junit4] 2> 1047324 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=13.0234375, floorSegmentMB=1.796875, forceMergeDeletesPctAllowed=11.803778042135761, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4022481367277221
[junit4] 2> 1047348 WARN (coreLoadExecutor-2118-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> 1047356 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1047356 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1047356 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1047356 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1047357 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1047358 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@315d6727[collection1] main]
[junit4] 2> 1047358 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1047358 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1047359 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1047359 INFO (searcherExecutor-2119-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@315d6727[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1047360 INFO (coreLoadExecutor-2118-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1556355151903588352
[junit4] 2> 1047365 INFO (coreZkRegister-2113-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1047365 INFO (coreZkRegister-2113-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1047365 INFO (coreZkRegister-2113-thread-1) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:46110/ry/collection1/
[junit4] 2> 1047365 INFO (coreZkRegister-2113-thread-1) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1047365 INFO (coreZkRegister-2113-thread-1) [ ] o.a.s.c.SyncStrategy https://127.0.0.1:46110/ry/collection1/ has no replicas
[junit4] 2> 1047366 INFO (coreZkRegister-2113-thread-1) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:46110/ry/collection1/ shard1
[junit4] 2> 1047517 INFO (coreZkRegister-2113-thread-1) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1048139 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-2-001/cores/collection1
[junit4] 2> 1048139 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-2-001
[junit4] 2> 1048141 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1048142 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@730310a7{/ry,null,AVAILABLE}
[junit4] 2> 1048142 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@c56a8ce{SSL,[ssl, http/1.1]}{127.0.0.1:43528}
[junit4] 2> 1048143 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.Server Started @1050620ms
[junit4] 2> 1048143 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ry, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/tempDir-001/jetty2, hostPort=43528, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-2-001/cores}
[junit4] 2> 1048143 ERROR (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1048143 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 1048143 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1048143 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1048143 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T21:18:56.360705Z
[junit4] 2> 1048145 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1048145 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-2-001/solr.xml
[junit4] 2> 1048152 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39981/solr
[junit4] 2> 1048156 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1048158 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43528_ry
[junit4] 2> 1048159 INFO (zkCallback-793-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1048159 INFO (zkCallback-797-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1048159 INFO (zkCallback-802-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1048159 INFO (zkCallback-808-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1048180 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-2-001/cores
[junit4] 2> 1048180 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1048181 INFO (OverseerStateUpdate-97272196760928261-127.0.0.1:44637_ry-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1049189 WARN (coreLoadExecutor-2129-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1049190 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1049199 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1049259 WARN (coreLoadExecutor-2129-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> 1049260 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1049265 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1049265 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-2-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 1049265 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d05de24
[junit4] 2> 1049268 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=13.0234375, floorSegmentMB=1.796875, forceMergeDeletesPctAllowed=11.803778042135761, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4022481367277221
[junit4] 2> 1049303 WARN (coreLoadExecutor-2129-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> 1049311 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1049311 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1049312 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1049312 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1049313 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1049313 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@549dfddb[collection1] main]
[junit4] 2> 1049314 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1049314 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1049314 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1049315 INFO (searcherExecutor-2130-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@549dfddb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1049316 INFO (coreLoadExecutor-2129-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1556355153954603008
[junit4] 2> 1049319 INFO (coreZkRegister-2124-thread-1) [ ] o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 1049319 INFO (updateExecutor-805-thread-1) [ ] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1049319 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 1049319 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 1049319 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
[junit4] 2> 1049319 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1049319 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [https://127.0.0.1:46110/ry/collection1/] and I am [https://127.0.0.1:43528/ry/collection1/]
[junit4] 2> 1049321 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.c.RecoveryStrategy Sending prep recovery command to [https://127.0.0.1:46110/ry]; [WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:43528_ry&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1049337 INFO (qtp541907910-5326) [ ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1049337 INFO (qtp541907910-5326) [ ] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 (shard1 of collection1) have state: recovering
[junit4] 2> 1049337 INFO (qtp541907910-5326) [ ] 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:43528_ry, coreNodeName=core_node2, onlyIfActiveCheckResult=false, nodeProps: core_node2:{"core":"collection1","base_url":"https://127.0.0.1:43528/ry","node_name":"127.0.0.1:43528_ry","state":"down"}
[junit4] 2> 1050083 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-3-001/cores/collection1
[junit4] 2> 1050084 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-3-001
[junit4] 2> 1050086 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1050087 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6fe90ce9{/ry,null,AVAILABLE}
[junit4] 2> 1050087 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5ef76cc6{SSL,[ssl, http/1.1]}{127.0.0.1:46247}
[junit4] 2> 1050088 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.e.j.s.Server Started @1052565ms
[junit4] 2> 1050088 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ry, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/tempDir-001/jetty3, hostPort=46247, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-3-001/cores}
[junit4] 2> 1050088 ERROR (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1050088 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 1050088 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1050088 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1050088 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T21:18:58.305851Z
[junit4] 2> 1050091 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1050091 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-3-001/solr.xml
[junit4] 2> 1050097 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39981/solr
[junit4] 2> 1050101 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1050102 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46247_ry
[junit4] 2> 1050102 INFO (zkCallback-797-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1050103 INFO (zkCallback-815-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1050103 INFO (zkCallback-802-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1050102 INFO (zkCallback-808-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1050102 INFO (zkCallback-793-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1050132 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-3-001/cores
[junit4] 2> 1050132 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1050133 INFO (OverseerStateUpdate-97272196760928261-127.0.0.1:44637_ry-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1050337 INFO (qtp541907910-5326) [ ] 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:43528_ry, coreNodeName=core_node2, onlyIfActiveCheckResult=false, nodeProps: core_node2:{"core":"collection1","base_url":"https://127.0.0.1:43528/ry","node_name":"127.0.0.1:43528_ry","state":"recovering"}
[junit4] 2> 1050337 INFO (qtp541907910-5326) [ ] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1050337 INFO (qtp541907910-5326) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:43528_ry&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> 1051140 WARN (coreLoadExecutor-2140-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1051141 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1051162 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1051213 WARN (coreLoadExecutor-2140-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> 1051215 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1051233 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1051233 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-3-001/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.PeerSyncReplicationTest_89BD0B901B2D8976-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 1051234 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d05de24
[junit4] 2> 1051236 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=13.0234375, floorSegmentMB=1.796875, forceMergeDeletesPctAllowed=11.803778042135761, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4022481367277221
[junit4] 2> 1051260 WARN (coreLoadExecutor-2140-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> 1051268 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1051268 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1051269 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1051269 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1051269 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1051270 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5e530fa8[collection1] main]
[junit4] 2> 1051271 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1051271 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1051271 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1051272 INFO (searcherExecutor-2141-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5e530fa8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1051273 INFO (coreLoadExecutor-2140-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1556355156006666240
[junit4] 2> 1051275 INFO (coreZkRegister-2135-thread-1) [ ] o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 1051276 INFO (updateExecutor-812-thread-1) [ ] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1051276 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 1051277 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 1051277 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1]
[junit4] 2> 1051277 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1051277 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.c.RecoveryStrategy Publishing state of core [collection1] as recovering, leader is [https://127.0.0.1:46110/ry/collection1/] and I am [https://127.0.0.1:46247/ry/collection1/]
[junit4] 2> 1051279 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.c.RecoveryStrategy Sending prep recovery command to [https://127.0.0.1:46110/ry]; [WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:46247_ry&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1051282 INFO (qtp541907910-5331) [ ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1051283 INFO (qtp541907910-5331) [ ] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1 (shard1 of collection1) have state: recovering
[junit4] 2> 1051283 INFO (qtp541907910-5331) [ ] 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:46247_ry, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"https://127.0.0.1:46247/ry","node_name":"127.0.0.1:46247_ry","state":"down"}
[junit4] 2> 1051692 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1051693 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Wait for recoveries to finish - wait 30 for each attempt
[junit4] 2> 1051693 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):30
[junit4] 2> 1052283 INFO (qtp541907910-5331) [ ] 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:46247_ry, coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps: core_node3:{"core":"collection1","base_url":"https://127.0.0.1:46247/ry","node_name":"127.0.0.1:46247_ry","state":"recovering"}
[junit4] 2> 1052283 INFO (qtp541907910-5331) [ ] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1052283 INFO (qtp541907910-5331) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:46247_ry&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=0 QTime=1001
[junit4] 2> 1057338 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.c.RecoveryStrategy Attempting to PeerSync from [https://127.0.0.1:46110/ry/collection1/] - recoveringAfterStartup=[true]
[junit4] 2> 1057338 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.u.PeerSync PeerSync: core=collection1 url=https://127.0.0.1:43528/ry START replicas=[https://127.0.0.1:46110/ry/collection1/] nUpdates=1000
[junit4] 2> 1057341 INFO (qtp541907910-5327) [ ] 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> 1057341 INFO (qtp541907910-5327) [ ] o.a.s.c.S.Request [collection1] webapp=/ry path=/get params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1057342 INFO (recoveryExecutor-806-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> 1057342 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync
[junit4] 2> 1057342 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1057342 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1057343 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1057343 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful.
[junit4] 2> 1057343 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync.
[junit4] 2> 1057343 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 1057343 INFO (recoveryExecutor-806-thread-1) [ ] o.a.s.c.RecoveryStrategy Registering as Active after recovery.
[junit4] 2> 1059284 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.c.RecoveryStrategy Attempting to PeerSync from [https://127.0.0.1:46110/ry/collection1/] - recoveringAfterStartup=[true]
[junit4] 2> 1059284 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.u.PeerSync PeerSync: core=collection1 url=https://127.0.0.1:46247/ry START replicas=[https://127.0.0.1:46110/ry/collection1/] nUpdates=1000
[junit4] 2> 1059287 INFO (qtp541907910-5327) [ ] 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> 1059287 INFO (qtp541907910-5327) [ ] o.a.s.c.S.Request [collection1] webapp=/ry path=/get params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1059288 INFO (recoveryExecutor-813-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> 1059288 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.u.PeerSync We are already in sync. No need to do a PeerSync
[junit4] 2> 1059288 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1059288 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1059288 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1059288 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.c.RecoveryStrategy PeerSync stage of recovery was successful.
[junit4] 2> 1059288 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.c.RecoveryStrategy Replaying updates buffered during PeerSync.
[junit4] 2> 1059288 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.c.RecoveryStrategy No replay needed.
[junit4] 2> 1059288 INFO (recoveryExecutor-813-thread-1) [ ] o.a.s.c.RecoveryStrategy Registering as Active after recovery.
[junit4] 2> 1059693 INFO (TEST-PeerSyncReplicationTest.test-seed#[89BD0B901B2D8976]) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 1059708 INFO (qtp1783557402-5290) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1059708 INFO (qtp1783557402-5290) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1059708 INFO (qtp1783557402-5290) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1059708 INFO (qtp1783557402-5290) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 0
[junit4] 2> 1059713 INFO (qtp541907910-5326) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1059713 INFO (qtp541907910-5326) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1059714 INFO (qtp541907910-5326) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1059714 INFO (qtp541907910-5326) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46110/ry/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 1059734 INFO (qtp2100436770-5354) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1059734 INFO (qtp807242981-5389) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1059734 INFO (qtp2100436770-5354) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1059735 INFO (qtp807242981-5389) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1059735 INFO (qtp2100436770-5354) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1059735 INFO (qtp807242981-5389) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1059736 INFO (qtp807242981-5389) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46110/ry/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2
[junit4] 2> 1059736 INFO (qtp2100436770-5354) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46110/ry/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 2
[junit4] 2> 1059737 INFO (qtp541907910-5327) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 26
[junit4] 2> 1059739 INFO (qtp541907910-5327) [ ] o.a.s.c.S.Request [collection1] webapp=/ry path=/select params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 1059744 INFO (qtp2100436770-5361) [ ] o.a.s.c.S.Request [collection1] webapp=/ry path=/select params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 1059747 INFO (qtp807242981-5389) [ ] o.a.s.c.S.Request [collection1] webapp=/ry path=/select params={q=*:*&distrib=false&tests=checkShardConsistency&rows=0&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 1061750 INFO (qtp1783557402-5295) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1556355166990499840)} 0 1
[junit4] 2> 1061754 INFO (qtp2100436770-5359) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&_version_=-1556355166992596992&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{deleteByQuery=*:* (-1556355166992596992)} 0 1
[junit4] 2> 1061754 INFO (qtp807242981-5394) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&_version_=-1556355166992596992&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{deleteByQuery=*:* (-1556355166992596992)} 0 1
[junit4] 2> 1061754 INFO (qtp541907910-5333) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1556355166992596992)} 0 3
[junit4] 2> 1061758 INFO (qtp807242981-5395) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[0 (1556355166998888448)]} 0 0
[junit4] 2> 1061758 INFO (qtp2100436770-5360) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[0 (1556355166998888448)]} 0 0
[junit4] 2> 1061758 INFO (qtp541907910-5332) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[0 (1556355166998888448)]} 0 2
[junit4] 2> 1061760 INFO (qtp2100436770-5354) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[1 (1556355167002034176)]} 0 0
[junit4] 2> 1061760 INFO (qtp807242981-5396) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[1 (1556355167002034176)]} 0 0
[junit4] 2> 1061760 INFO (qtp541907910-5326) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[1 (1556355167002034176)]} 0 1
[junit4] 2> 1061763 INFO (qtp807242981-5389) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[2 (1556355167004131328)]} 0 0
[junit4] 2> 1061763 INFO (qtp2100436770-5361) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[2 (1556355167004131328)]} 0 0
[junit4] 2> 1061763 INFO (qtp541907910-5327) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[2 (1556355167004131328)]} 0 2
[junit4] 2> 1061765 INFO (qtp2100436770-5359) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[3 (1556355167007277056)]} 0 0
[junit4] 2> 1061765 INFO (qtp807242981-5394) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[3 (1556355167007277056)]} 0 0
[junit4] 2> 1061765 INFO (qtp541907910-5333) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[3 (1556355167007277056)]} 0 1
[junit4] 2> 1061766 INFO (qtp2100436770-5360) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[4 (1556355167008325632)]} 0 0
[junit4] 2> 1061766 INFO (qtp807242981-5395) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[4 (1556355167008325632)]} 0 0
[junit4] 2> 1061767 INFO (qtp541907910-5332) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[4 (1556355167008325632)]} 0 1
[junit4] 2> 1061768 INFO (qtp807242981-5396) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[5 (1556355167010422784)]} 0 0
[junit4] 2> 1061768 INFO (qtp2100436770-5354) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[5 (1556355167010422784)]} 0 0
[junit4] 2> 1061768 INFO (qtp541907910-5326) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[5 (1556355167010422784)]} 0 0
[junit4] 2> 1061769 INFO (qtp807242981-5389) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[6 (1556355167011471360)]} 0 0
[junit4] 2> 1061769 INFO (qtp2100436770-5361) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[6 (1556355167011471360)]} 0 0
[junit4] 2> 1061769 INFO (qtp541907910-5327) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[6 (1556355167011471360)]} 0 0
[junit4] 2> 1061770 INFO (qtp807242981-5389) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[7 (1556355167012519936)]} 0 0
[junit4] 2> 1061771 INFO (qtp2100436770-5359) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[7 (1556355167012519936)]} 0 1
[junit4] 2> 1061772 INFO (qtp541907910-5333) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[7 (1556355167012519936)]} 0 2
[junit4] 2> 1061773 INFO (qtp2100436770-5360) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[8 (1556355167015665664)]} 0 0
[junit4] 2> 1061773 INFO (qtp807242981-5395) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[8 (1556355167015665664)]} 0 0
[junit4] 2> 1061773 INFO (qtp541907910-5332) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[8 (1556355167015665664)]} 0 0
[junit4] 2> 1061774 INFO (qtp2100436770-5354) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[9 (1556355167016714240)]} 0 0
[junit4] 2> 1061774 INFO (qtp807242981-5396) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[9 (1556355167016714240)]} 0 0
[junit4] 2> 1061774 INFO (qtp541907910-5326) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[9 (1556355167016714240)]} 0 0
[junit4] 2> 1061775 INFO (qtp807242981-5394) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[10 (1556355167017762816)]} 0 0
[junit4] 2> 1061775 INFO (qtp2100436770-5361) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[10 (1556355167017762816)]} 0 0
[junit4] 2> 1061775 INFO (qtp541907910-5327) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[10 (1556355167017762816)]} 0 0
[junit4] 2> 1061776 INFO (qtp2100436770-5359) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[11 (1556355167019859968)]} 0 0
[junit4] 2> 1061776 INFO (qtp807242981-5394) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[11 (1556355167019859968)]} 0 0
[junit4] 2> 1061776 INFO (qtp541907910-5333) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[11 (1556355167019859968)]} 0 0
[junit4] 2> 1061777 INFO (qtp2100436770-5360) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[12 (1556355167020908544)]} 0 0
[junit4] 2> 1061777 INFO (qtp807242981-5395) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[12 (1556355167020908544)]} 0 0
[junit4] 2> 1061777 INFO (qtp541907910-5332) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[12 (1556355167020908544)]} 0 0
[junit4] 2> 1061778 INFO (qtp2100436770-5354) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[13 (1556355167021957120)]} 0 0
[junit4] 2> 1061778 INFO (qtp807242981-5396) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[13 (1556355167021957120)]} 0 0
[junit4] 2> 1061779 INFO (qtp541907910-5326) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[13 (1556355167021957120)]} 0 0
[junit4] 2> 1061780 INFO (qtp807242981-5389) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[14 (1556355167023005696)]} 0 0
[junit4] 2> 1061780 INFO (qtp2100436770-5361) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[14 (1556355167023005696)]} 0 0
[junit4] 2> 1061780 INFO (qtp541907910-5327) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[14 (1556355167023005696)]} 0 0
[junit4] 2> 1061781 INFO (qtp807242981-5394) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[15 (1556355167024054272)]} 0 0
[junit4] 2> 1061781 INFO (qtp2100436770-5359) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[15 (1556355167024054272)]} 0 0
[junit4] 2> 1061781 INFO (qtp541907910-5333) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[15 (1556355167024054272)]} 0 0
[junit4] 2> 1061783 INFO (qtp2100436770-5360) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[16 (1556355167025102848)]} 0 1
[junit4] 2> 1061784 INFO (qtp807242981-5394) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[16 (1556355167025102848)]} 0 1
[junit4] 2> 1061784 INFO (qtp541907910-5332) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[16 (1556355167025102848)]} 0 2
[junit4] 2> 1061785 INFO (qtp807242981-5389) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[17 (1556355167028248576)]} 0 0
[junit4] 2> 1061785 INFO (qtp2100436770-5354) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[17 (1556355167028248576)]} 0 0
[junit4] 2> 1061785 INFO (qtp541907910-5326) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[17 (1556355167028248576)]} 0 1
[junit4] 2> 1061786 INFO (qtp2100436770-5359) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[18 (1556355167030345728)]} 0 0
[junit4] 2> 1061787 INFO (qtp807242981-5389) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[18 (1556355167030345728)]} 0 0
[junit4] 2> 1061787 INFO (qtp541907910-5327) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[18 (1556355167030345728)]} 0 0
[junit4] 2> 1061788 INFO (qtp2100436770-5360) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[19 (1556355167031394304)]} 0 0
[junit4] 2> 1061788 INFO (qtp807242981-5396) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[19 (1556355167031394304)]} 0 0
[junit4] 2> 1061788 INFO (qtp541907910-5333) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[19 (1556355167031394304)]} 0 0
[junit4] 2> 1061789 INFO (qtp2100436770-5361) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[20 (1556355167032442880)]} 0 0
[junit4] 2> 1061789 INFO (qtp807242981-5394) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[20 (1556355167032442880)]} 0 0
[junit4] 2> 1061789 INFO (qtp541907910-5332) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[20 (1556355167032442880)]} 0 0
[junit4] 2> 1061790 INFO (qtp807242981-5395) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[21 (1556355167033491456)]} 0 0
[junit4] 2> 1061790 INFO (qtp2100436770-5354) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[21 (1556355167033491456)]} 0 0
[junit4] 2> 1061790 INFO (qtp541907910-5326) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[21 (1556355167033491456)]} 0 0
[junit4] 2> 1061791 INFO (qtp807242981-5395) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[22 (1556355167035588608)]} 0 0
[junit4] 2> 1061791 INFO (qtp2100436770-5359) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[22 (1556355167035588608)]} 0 0
[junit4] 2> 1061791 INFO (qtp541907910-5327) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[22 (1556355167035588608)]} 0 0
[junit4] 2> 1061792 INFO (qtp807242981-5396) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[23 (1556355167036637184)]} 0 0
[junit4] 2> 1061792 INFO (qtp2100436770-5360) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[23 (1556355167036637184)]} 0 0
[junit4] 2> 1061793 INFO (qtp541907910-5333) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={wt=javabin&version=2}{add=[23 (1556355167036637184)]} 0 0
[junit4] 2> 1061793 INFO (qtp807242981-5394) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[24 (1556355167037685760)]} 0 0
[junit4] 2> 1061794 INFO (qtp2100436770-5360) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/ry path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:46110/ry/collection1/&wt=javabin&version=2}{add=[24 (1556355167037685760)]} 0 0
[junit4]
[...truncated too long message...]
erStateUpdate-97272250587938822-127.0.0.1:45620_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:45620_solr
[junit4] 2> 1866630 INFO (jetty-launcher-4619-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1866631 ERROR (Thread[Thread-3593,5,TGRP-TestSolrCloudWithDelegationTokens]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 1866631 WARN (zkCallback-4630-thread-3) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1866631 INFO (jetty-launcher-4619-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1866631 ERROR (Thread[Thread-3592,5,TGRP-TestSolrCloudWithDelegationTokens]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 1866637 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x1599498b6cc0007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1866638 INFO (jetty-launcher-4619-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@328496dc{/solr,null,UNAVAILABLE}
[junit4] 2> 1866638 INFO (jetty-launcher-4619-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@135e37c1{/solr,null,UNAVAILABLE}
[junit4] 2> 1866638 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[89BD0B901B2D8976]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45500 45500
[junit4] 2> 1866661 INFO (Thread-3574) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45500 45500
[junit4] 2> 1866661 WARN (Thread-3574) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 10 /solr/aliases.json
[junit4] 2> 10 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 10 /solr/clusterstate.json
[junit4] 2> 4 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_7
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_6
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_9
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_8
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_1
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_3
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_2
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_2
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_5
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_4
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_10
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 10 /solr/live_nodes
[junit4] 2> 10 /solr/collections
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
[junit4] 2>
[junit4] 2> 1866662 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[89BD0B901B2D8976]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestSolrCloudWithDelegationTokens_89BD0B901B2D8976-001
[junit4] 2> Jan 12, 2017 9:32:35 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 5 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=RandomSimilarity(queryNorm=false): {}, locale=pt-MZ, timezone=America/Shiprock
[junit4] 2> NOTE: Linux 4.4.0-53-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=149216816,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [CdcrRequestHandlerTest, DataDrivenBlockJoinTest, DistributedFacetExistsSmallTest, TestFieldCacheSanityChecker, CdcrBootstrapTest, TestMiniSolrCloudCluster, RestartWhileUpdatingTest, TestCSVResponseWriter, TestConfigSets, SolrCoreCheckLockOnStartupTest, TestIndexSearcher, DebugComponentTest, OverseerTaskQueueTest, OutOfBoxZkACLAndCredentialsProvidersTest, StatelessScriptUpdateProcessorFactoryTest, LukeRequestHandlerTest, BlockJoinFacetDistribTest, LeaderInitiatedRecoveryOnShardRestartTest, SimpleFacetsTest, TestSolrCloudWithKerberosAlt, SuggesterWFSTTest, TestConfigSetsAPIZkFailure, TestMergePolicyConfig, TestTolerantSearch, TestHashQParserPlugin, StatsComponentTest, TestCloudDeleteByQuery, PKIAuthenticationIntegrationTest, HdfsUnloadDistributedZkTest, ForceLeaderTest, SuggesterTSTTest, TestStressLiveNodes, TestSolrJ, TestClassicSimilarityFactory, TestClusterStateMutator, TestReloadDeadlock, StatsReloadRaceTest, TestSmileRequest, LeaderElectionTest, TestUniqueKeyFieldResource, VersionInfoTest, DistributedQueryComponentCustomSortTest, SolrPluginUtilsTest, TestChildDocTransformer, TestOnReconnectListenerSupport, TestReversedWildcardFilterFactory, TestIntervalFaceting, RAMDirectoryFactoryTest, SharedFSAutoReplicaFailoverUtilsTest, PrimitiveFieldTypeTest, TestIBSimilarityFactory, TestRTimerTree, DateFieldTest, XmlUpdateRequestHandlerTest, SpatialHeatmapFacetsTest, TestComplexPhraseLeadingWildcard, TestConfigOverlay, TestOverriddenPrefixQueryForCustomFieldType, UUIDUpdateProcessorFallbackTest, TestStressReorder, AddBlockUpdateTest, DocValuesMultiTest, TestTrackingShardHandlerFactory, GraphQueryTest, TestDistributedMissingSort, AssignTest, RemoteQueryErrorTest, CoreAdminHandlerTest, CoreAdminRequestStatusTest, TestUnifiedSolrHighlighter, TestDocBasedVersionConstraints, EchoParamsTest, TestManagedSchemaAPI, JSONWriterTest, DeleteInactiveReplicaTest, SliceStateTest, TestRuleBasedAuthorizationPlugin, SuggesterTest, SolrMetricsIntegrationTest, BadIndexSchemaTest, TestRequestStatusCollectionAPI, PrimUtilsTest, MetricsHandlerTest, OutputWriterTest, HighlighterConfigTest, MergeStrategyTest, TestMaxScoreQueryParser, UtilsToolTest, SolrTestCaseJ4Test, TestZkChroot, SolrCoreMetricManagerTest, TestShardHandlerFactory, ConnectionReuseTest, TestDocTermOrds, TestWriterPerf, ResponseHeaderTest, TestSerializedLuceneMatchVersion, TestSolrDeletionPolicy1, ParsingFieldUpdateProcessorsTest, TestRestManager, BlobRepositoryCloudTest, TestRestoreCore, TestJsonRequest, TestFieldTypeCollectionResource, TestDistribIDF, TestSolrConfigHandlerCloud, MBeansHandlerTest, ManagedSchemaRoundRobinCloudTest, TestCollationField, AnalyticsMergeStrategyTest, TestCloudSchemaless, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestDistributedGrouping, DistributedSpellCheckComponentTest, TestRealTimeGet, TestReload, SolrCoreTest, TestGroupingSearch, QueryElevationComponentTest, TestSort, TestFiltering, SignatureUpdateProcessorFactoryTest, SuggesterFSTTest, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, TestUpdate, QueryEqualityTest, DistanceFunctionTest, XsltUpdateRequestHandlerTest, CacheHeaderTest, DocumentAnalysisRequestHandlerTest, RequestHandlersTest, FieldAnalysisRequestHandlerTest, LoggingHandlerTest, IndexSchemaTest, TestAnalyzedSuggestions, TestPHPSerializedResponseWriter, SOLR749Test, TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, TestQuerySenderNoQuery, SolrIndexConfigTest, TestSolrDeletionPolicy2, MultiTermTest, TestDocSet, TestBinaryField, TestSearchPerf, NumericFieldsTest, TestConfig, ExternalFileFieldSortTest, TestSolrCoreProperties, DirectSolrConnectionTest, TestLuceneMatchVersion, TestPhraseSuggestions, TestCharFilters, SynonymTokenizerTest, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestFastWriter, TestJmxMonitoredMap, ResourceLoaderTest, TestFastOutputStream, URLClassifyProcessorTest, PluginInfoTest, TestFastLRUCache, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, AsyncCallRequestStatusResponseTest, BaseCdcrDistributedZkTest, CdcrReplicationHandlerTest, CdcrVersionReplicationTest, CloudExitableDirectoryReaderTest, CollectionReloadTest, CollectionsAPISolrJTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, DistribJoinFromCollectionTest, DistributedVersionInfoTest, LeaderFailureAfterFreshStartTest, SolrCloudExampleTest, TestCloudInspectUtil, TestCloudPivotFacet, TestConfigSetsAPI, TestExclusionRuleCollectionAccess, TestHdfsCloudBackupRestore, TestLeaderElectionWithEmptyReplica, TestLeaderInitiatedRecoveryThread, TestSegmentSorting, TestSizeLimitedDistributedMap, TestSolrCloudWithDelegationTokens]
[junit4] Completed [518/678 (2!)] on J2 in 2.83s, 8 tests, 1 failure <<< FAILURES!
[...truncated 52032 lines...]
[JENKINS] Lucene-Solr-master-Linux (32bit/jdk1.8.0_112) - Build #
18762 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/18762/
Java: 32bit/jdk1.8.0_112 -client -XX:+UseSerialGC
3 tests failed.
FAILED: org.apache.solr.cloud.DistributedVersionInfoTest.testReplicaVersionHandling
Error Message:
Captured an uncaught exception in thread: Thread[id=5887, name=Thread-1718, state=RUNNABLE, group=TGRP-DistributedVersionInfoTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=5887, name=Thread-1718, state=RUNNABLE, group=TGRP-DistributedVersionInfoTest]
at __randomizedtesting.SeedInfo.seed([1063FF0B0248691D:CC9A28F1A033A35C]:0)
Caused by: java.lang.IllegalArgumentException: bound must be positive
at __randomizedtesting.SeedInfo.seed([1063FF0B0248691D]:0)
at java.util.Random.nextInt(Random.java:388)
at org.apache.solr.cloud.DistributedVersionInfoTest$3.run(DistributedVersionInfoTest.java:204)
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestDetails
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([1063FF0B0248691D:6A3EDC7E94104095]:0)
at org.apache.solr.handler.TestReplicationHandler.doTestDetails(TestReplicationHandler.java:312)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.PeerSyncReplicationTest.test
Error Message:
PeerSynced node did not become leader expected:<CloudJettyRunner [url=https://127.0.0.1:36942/collection1]> but was:<CloudJettyRunner [url=https://127.0.0.1:41947/collection1]>
Stack Trace:
java.lang.AssertionError: PeerSynced node did not become leader expected:<CloudJettyRunner [url=https://127.0.0.1:36942/collection1]> but was:<CloudJettyRunner [url=https://127.0.0.1:41947/collection1]>
at __randomizedtesting.SeedInfo.seed([1063FF0B0248691D:9837C0D1ACB404E5]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10944 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/init-core-data-001
[junit4] 2> 14481 INFO (SUITE-TestReplicationHandler-seed#[1063FF0B0248691D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 14483 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestRepeater
[junit4] 2> 14483 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/collection1
[junit4] 2> 14489 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 14490 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@19b0c8a{/solr,null,AVAILABLE}
[junit4] 2> 14491 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@dbf775{HTTP/1.1,[http/1.1]}{127.0.0.1:44786}
[junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server Started @15812ms
[junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=44786}
[junit4] 2> 14492 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solrâ„¢ version 7.0.0
[junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 14492 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T23:59:49.255Z
[junit4] 2> 14493 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/solr.xml
[junit4] 2> 14512 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/.
[junit4] 2> 14512 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 14532 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 14552 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 14555 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 14557 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/./collection1
[junit4] 2> 14557 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/./collection1/data/]
[junit4] 2> 14559 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=2.04296875, noCFSRatio=0.0]
[junit4] 2> 14574 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 14574 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 14574 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=5.3408203125, floorSegmentMB=0.2392578125, forceMergeDeletesPctAllowed=1.1107824703843139, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5458399542355805
[junit4] 2> 14575 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@14cb0f2[collection1] main]
[junit4] 2> 14575 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-001/collection1/conf
[junit4] 2> 14576 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 14576 INFO (coreLoadExecutor-61-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 14576 INFO (searcherExecutor-62-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14cb0f2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 15059 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1
[junit4] 2> 15062 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 15063 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@194852b{/solr,null,AVAILABLE}
[junit4] 2> 15064 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@e1b1a9{HTTP/1.1,[http/1.1]}{127.0.0.1:36464}
[junit4] 2> 15064 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server Started @16384ms
[junit4] 2> 15064 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=36464}
[junit4] 2> 15064 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 15065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solrâ„¢ version 7.0.0
[junit4] 2> 15065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 15065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 15065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T23:59:49.828Z
[junit4] 2> 15065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/solr.xml
[junit4] 2> 15083 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/.
[junit4] 2> 15083 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 15107 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 15124 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 15127 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 15128 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/./collection1
[junit4] 2> 15128 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/./collection1/data/]
[junit4] 2> 15130 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=2.04296875, noCFSRatio=0.0]
[junit4] 2> 15139 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 15139 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 15139 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=5.3408203125, floorSegmentMB=0.2392578125, forceMergeDeletesPctAllowed=1.1107824703843139, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5458399542355805
[junit4] 2> 15140 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11592bd[collection1] main]
[junit4] 2> 15140 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1/conf
[junit4] 2> 15149 INFO (coreLoadExecutor-71-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 15149 INFO (searcherExecutor-72-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11592bd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 15162 INFO (qtp23410299-71) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 15165 INFO (indexFetcher-76-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 15165 INFO (indexFetcher-76-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 15165 INFO (indexFetcher-76-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 15626 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@e1b1a9{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 15626 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=9150439
[junit4] 2> 15626 INFO (coreCloseExecutor-77-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@103629d
[junit4] 2> 15628 INFO (coreCloseExecutor-77-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 15629 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 15644 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@194852b{/solr,null,UNAVAILABLE}
[junit4] 2> 15645 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 15646 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@743a5c{/solr,null,AVAILABLE}
[junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6ee494{HTTP/1.1,[http/1.1]}{127.0.0.1:36031}
[junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server Started @16967ms
[junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=36031}
[junit4] 2> 15647 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solrâ„¢ version 7.0.0
[junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 15647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T23:59:50.410Z
[junit4] 2> 15648 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/solr.xml
[junit4] 2> 15668 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/.
[junit4] 2> 15668 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 15690 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 15706 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 15710 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 15711 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/./collection1
[junit4] 2> 15711 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/./collection1/data/]
[junit4] 2> 15713 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=2.04296875, noCFSRatio=0.0]
[junit4] 2> 15728 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 15728 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 15728 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=5.3408203125, floorSegmentMB=0.2392578125, forceMergeDeletesPctAllowed=1.1107824703843139, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5458399542355805
[junit4] 2> 15729 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17cad8c[collection1] main]
[junit4] 2> 15729 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-002/collection1/conf
[junit4] 2> 15729 INFO (coreLoadExecutor-84-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 15729 INFO (searcherExecutor-85-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17cad8c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 16213 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/collection1
[junit4] 2> 16225 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 16226 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e49fbd{/solr,null,AVAILABLE}
[junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@f35f38{HTTP/1.1,[http/1.1]}{127.0.0.1:37049}
[junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.e.j.s.Server Started @17547ms
[junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=37049}
[junit4] 2> 16227 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solrâ„¢ version 7.0.0
[junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 16227 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 16228 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-12T23:59:50.990Z
[junit4] 2> 16228 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/solr.xml
[junit4] 2> 16239 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/.
[junit4] 2> 16239 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 16255 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 16270 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 16273 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 16277 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/./collection1
[junit4] 2> 16278 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/./collection1/data/]
[junit4] 2> 16279 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=2.04296875, noCFSRatio=0.0]
[junit4] 2> 16289 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 16289 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 16290 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=5.3408203125, floorSegmentMB=0.2392578125, forceMergeDeletesPctAllowed=1.1107824703843139, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5458399542355805
[junit4] 2> 16290 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a4f5b5[collection1] main]
[junit4] 2> 16290 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_1063FF0B0248691D-001/solr-instance-003/collection1/conf
[junit4] 2> 16291 WARN (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 16292 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 16292 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 16292 INFO (coreLoadExecutor-94-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 16292 INFO (searcherExecutor-95-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a4f5b5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 16796 INFO (qtp23410299-71) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 6
[junit4] 2> 16798 INFO (qtp23410299-72) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 16800 INFO (qtp23410299-66) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 16804 INFO (qtp23410299-67) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 16805 INFO (qtp23410299-67) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@11c8fa
[junit4] 2> 16810 INFO (qtp23410299-67) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bff1f7[collection1] main]
[junit4] 2> 16810 INFO (qtp23410299-67) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 16810 INFO (searcherExecutor-62-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1bff1f7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))}
[junit4] 2> 16810 INFO (qtp23410299-67) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 6
[junit4] 2> 16822 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 16826 INFO (qtp23410299-72) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 16827 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 16827 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591568
[junit4] 2> 16827 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 16827 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 16832 INFO (qtp23410299-66) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=3
[junit4] 2> 16832 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4
[junit4] 2> 16835 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=0.716796875, floorSegmentMB=0.36328125, forceMergeDeletesPctAllowed=4.080294105294002, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 16836 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 16836 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@b0fa15 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1b56f0c))
[junit4] 2> 16848 INFO (qtp23410299-67) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=3
[junit4] 2> 16855 INFO (qtp23410299-68) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 16857 INFO (qtp23410299-72) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 16859 INFO (qtp23410299-66) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 16867 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1997) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@b0fa15 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1b56f0c))
[junit4] 2> 16869 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 16871 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 16873 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@aa2f5b[collection1] main]
[junit4] 2> 16873 INFO (searcherExecutor-95-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@aa2f5b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))}
[junit4] 2> 16873 INFO (qtp7757891-126) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:44786/solr/collection1/replication&command=fetchindex} status=0 QTime=52
[junit4] 2> 16883 INFO (qtp7757891-128) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=1
[junit4] 2> 16885 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs
[junit4] 2> 16887 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 16891 INFO (qtp7757891-126) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 16891 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 16891 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591568
[junit4] 2> 16891 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 16891 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 16894 INFO (qtp7757891-122) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1
[junit4] 2> 16894 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4
[junit4] 2> 16897 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=0.716796875, floorSegmentMB=0.36328125, forceMergeDeletesPctAllowed=4.080294105294002, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 16898 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 16898 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1520137 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1221a8b))
[junit4] 2> 16899 INFO (qtp7757891-128) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 16902 INFO (qtp7757891-123) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 16904 INFO (qtp7757891-126) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 16907 INFO (qtp7757891-122) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 16908 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1997) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1520137 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1221a8b))
[junit4] 2> 16910 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 16912 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 16914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@211afc[collection1] main]
[junit4] 2> 16915 INFO (searcherExecutor-85-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@211afc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))}
[junit4] 2> 16915 INFO (qtp8868313-109) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:37049/solr/collection1/replication&command=fetchindex} status=0 QTime=28
[junit4] 2> 16919 INFO (qtp8868313-105) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=1
[junit4] 2> 16920 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs
[junit4] 2> 16921 INFO (qtp23410299-71) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 16923 INFO (qtp23410299-68) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 16929 WARN (qtp7757891-121) [ x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master
[junit4] 2> org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:634)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268)
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1729)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:927)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:166)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2324)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:658)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:464)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:296)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:113)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:80 [/127.0.0.1] failed: Connection refused (Connection refused)
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:151)
[junit4] 2> at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353)
[junit4] 2> at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380)
[junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236)
[junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
[junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
[junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:532)
[junit4] 2> ... 36 more
[junit4] 2> Caused by: java.net.ConnectException: Connection refused (Connection refused)
[junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
[junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit4] 2> at java.net.Socket.connect(Socket.java:589)
[junit4] 2> at org.apache.http.conn.socket.PlainConnectionSocketFactory.connectSocket(PlainConnectionSocketFactory.java:74)
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134)
[junit4] 2> ... 46 more
[junit4] 2> 16933 INFO (qtp7757891-121) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=7
[junit4] 2> 16942 INFO (qtp23410299-72) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 16943 INFO (qtp7757891-123) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 16945 WARN (qtp7757891-126) [ x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master
[junit4] 2> org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:634)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268)
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1729)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:927)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:166)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2324)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:658)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:464)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:296)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:113)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:80 [/127.0.0.1] failed: Connection refused (Connection refused)
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:151)
[junit4] 2> at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353)
[junit4] 2> at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380)
[junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236)
[junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
[junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
[junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:532)
[junit4] 2> ... 36 more
[junit4] 2> Caused by: java.net.ConnectException: Connection refused (Connection refused)
[junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
[junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit4] 2> at java.net.Socket.connect(Socket.java:589)
[junit4] 2> at org.apache.http.conn.socket.PlainConnectionSocketFactory.connectSocket(PlainConnectionSocketFactory.java:74)
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134)
[junit4] 2> ... 46 more
[junit4] 2> 16947 INFO (qtp7757891-126) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2
[junit4] 2> 16950 WARN (qtp7757891-122) [ x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master
[junit4] 2> org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:634)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268)
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1729)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:927)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:166)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2324)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:658)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:464)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:296)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:113)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:80 [/127.0.0.1] failed: Connection refused (Connection refused)
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:151)
[junit4] 2> at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353)
[junit4] 2> at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380)
[junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236)
[junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
[junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
[junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:532)
[junit4] 2> ... 36 more
[junit4] 2> Caused by: java.net.ConnectException: Connection refused (Connection refused)
[junit4] 2> at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4] 2> at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
[junit4] 2> at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
[junit4] 2> at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
[junit4] 2> at java.net.Socket.connect(Socket.java:589)
[junit4] 2> at org.apache.http.conn.socket.PlainConnectionSocketFactory.connectSocket(PlainConnectionSocketFactory.java:74)
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134)
[junit4] 2> ... 46 more
[junit4] 2> 16951 INFO (qtp7757891-122) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
[junit4] 2> 16956 INFO (qtp7757891-128) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 16956 INFO (qtp8868313-111) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3
[junit4] 2> 16958 INFO (qtp7757891-121) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 16959 INFO (qtp8868313-104) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 16962 INFO (qtp7757891-126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 2
[junit4] 2> 16964 INFO (qtp7757891-122) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 16965 INFO (qtp7757891-128) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 16966 INFO (qtp7757891-121) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 16967 INFO (qtp7757891-126) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 16967 INFO (qtp7757891-126) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@a32516
[junit4] 2> 16974 INFO (qtp7757891-126) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11dd95c[collection1] main]
[junit4] 2> 16974 INFO (qtp7757891-126) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 16974 INFO (searcherExecutor-95-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11dd95c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):c4)))}
[junit4] 2> 16974 INFO (qtp7757891-126) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 16976 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 16978 INFO (qtp23410299-67) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 16979 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 16979 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591568
[junit4] 2> 16979 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3
[junit4] 2> 16979 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 16981 INFO (qtp23410299-71) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1
[junit4] 2> 16981 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4
[junit4] 2> 16982 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=true) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1390701 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1867a7d))
[junit4] 2> 16983 INFO (qtp23410299-68) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 16987 INFO (qtp23410299-72) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 16989 INFO (qtp23410299-67) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 16991 INFO (qtp23410299-71) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 16992 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=true,bytesDownloaded=1997) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1390701 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1867a7d))
[junit4] 2> 16992 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher New index installed. Updating index properties... index=index.20170113055951745
[junit4] 2> 16994 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher removing old index directory MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1c17e04 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@346abc))
[junit4] 2> 16995 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=0.716796875, floorSegmentMB=0.36328125, forceMergeDeletesPctAllowed=4.080294105294002, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 16997 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 16999 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ad6747[collection1] main]
[junit4] 2> 16999 INFO (searcherExecutor-95-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ad6747[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3)))}
[junit4] 2> 16999 INFO (qtp7757891-127) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:44786/solr/collection1/replication&command=fetchindex} status=0 QTime=23
[junit4] 2> 17002 INFO (qtp7757891-122) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0
[junit4] 2> 17003 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs
[junit4] 2> 17004 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 17006 INFO (qtp7757891-123) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 17006 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 17006 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591568
[junit4] 2> 17006 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 17006 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 17006 INFO (qtp8868313-110) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:37049/solr/collection1/replication&command=fetchindex} status=0 QTime=2
[junit4] 2> 17008 INFO (qtp8868313-109) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0
[junit4] 2> 17010 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs
[junit4] 2> 17011 INFO (qtp23410299-66) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 1
[junit4] 2> 17013 INFO (qtp23410299-72) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 17014 INFO (qtp23410299-67) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 17015 INFO (qtp23410299-71) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 17015 INFO (qtp23410299-71) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@11c8fa
[junit4] 2> 17019 INFO (qtp23410299-71) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@aaeba5[collection1] main]
[junit4] 2> 17020 INFO (qtp23410299-71) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 17020 INFO (searcherExecutor-62-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@aaeba5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3) Uninverting(_1(7.0.0):c3)))}
[junit4] 2> 17020 INFO (qtp23410299-71) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 5
[junit4] 2> 17022 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 17024 INFO (qtp23410299-68) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 17024 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 17024 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591778
[junit4] 2> 17024 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 17024 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 17026 INFO (qtp23410299-72) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1
[junit4] 2> 17027 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7
[junit4] 2> 17029 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=0.716796875, floorSegmentMB=0.36328125, forceMergeDeletesPctAllowed=4.080294105294002, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 17031 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 17031 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@8f6cd2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cc85e9))
[junit4] 2> 17031 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 17031 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 17031 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 17034 INFO (qtp23410299-67) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 17039 INFO (qtp23410299-71) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 17041 INFO (qtp23410299-68) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 17043 INFO (qtp23410299-72) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 17043 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2060) : 0 secs (null bytes/sec) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@8f6cd2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cc85e9))
[junit4] 2> 17047 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 17049 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 17051 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f6b0e[collection1] main]
[junit4] 2> 17052 INFO (searcherExecutor-95-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1f6b0e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c3) Uninverting(_1(7.0.0):c3)))}
[junit4] 2> 17052 INFO (qtp7757891-128) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:44786/solr/collection1/replication&command=fetchindex} status=0 QTime=29
[junit4] 2> 17054 INFO (qtp7757891-121) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=6 status=0 QTime=0
[junit4] 2> 17055 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[1063FF0B0248691D]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 6 docs
[junit4] 2> 17056 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 17058 INFO (qtp7757891-126) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 17059 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 17059 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1484265591778
[junit4] 2> 17059 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 17059 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 17061 INFO (qtp7757891-123) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=2
[junit4] 2> 17062 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7
[junit4] 2> 17063 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=0.716796875, floorSegmentMB=0.36328125, forceMergeDeletesPctAllowed=4.080294105294002, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 17064 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 17065 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MetricsDirectory(MockDirectoryWrapper(RAMDirectory@34f41c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a9cfa1))
[junit4] 2> 17065 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 17065 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 17065 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 17066 INFO (qtp7757891-128) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 17067 INFO (qtp7757891-121) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 17070 INFO (qtp7757891-126) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 17075 INFO (qtp7757891-123) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segm
[...truncated too long message...]
o.a.s.c.OverseerTaskProcessor According to ZK I (id=97272926003920915-127.0.0.1:36942_-n_0000000004) am no longer a leader.
[junit4] 2> 1529891 INFO (OverseerStateUpdate-97272926003920915-127.0.0.1:36942_-n_0000000004) [n:127.0.0.1:36942_ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:36942_
[junit4] 2> 1531392 WARN (zkCallback-4067-thread-2-processing-n:127.0.0.1:36942_) [n:127.0.0.1:36942_ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1532246 WARN (zkCallback-4067-thread-1-processing-n:127.0.0.1:36942_) [n:127.0.0.1:36942_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Closed, skipping sync up.
[junit4] 2> 1532247 INFO (zkCallback-4067-thread-1-processing-n:127.0.0.1:36942_) [n:127.0.0.1:36942_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1073da5
[junit4] 2> 1532306 INFO (zkCallback-4067-thread-1-processing-n:127.0.0.1:36942_) [n:127.0.0.1:36942_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1532306 INFO (TEST-PeerSyncReplicationTest.test-seed#[1063FF0B0248691D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1532307 INFO (TEST-PeerSyncReplicationTest.test-seed#[1063FF0B0248691D]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1ae53cf{SSL,[ssl, http/1.1]}{127.0.0.1:36942}
[junit4] 2> 1532307 INFO (TEST-PeerSyncReplicationTest.test-seed#[1063FF0B0248691D]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@c577af{/,null,UNAVAILABLE}
[junit4] 2> 1532308 INFO (TEST-PeerSyncReplicationTest.test-seed#[1063FF0B0248691D]) [ ] o.a.s.c.ChaosMonkey monkey: stop shard! 44499
[junit4] 2> 1532309 INFO (TEST-PeerSyncReplicationTest.test-seed#[1063FF0B0248691D]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46282 46282
[junit4] 2> 1532334 INFO (Thread-2189) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46282 46282
[junit4] 2> 1532335 WARN (Thread-2189) [ ] 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> 5 /solr/security.json
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 4 /solr/collections/collection1/state.json
[junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/97272926003920906-core_node1-n_0000000000
[junit4] 2> 2 /solr/overseer_elect/election/97272926003920901-127.0.0.1:33691_-n_0000000000
[junit4] 2> 2 /solr/overseer_elect/election/97272926003920906-127.0.0.1:41947_-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 45 /solr/overseer/collection-queue-work
[junit4] 2> 27 /solr/overseer/queue
[junit4] 2> 14 /solr/overseer/queue-work
[junit4] 2> 6 /solr/collections
[junit4] 2> 5 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=PeerSyncReplicationTest -Dtests.method=test -Dtests.seed=1063FF0B0248691D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=zh-HK -Dtests.timezone=Europe/Bucharest -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 47.7s J2 | PeerSyncReplicationTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: PeerSynced node did not become leader expected:<CloudJettyRunner [url=https://127.0.0.1:36942/collection1]> but was:<CloudJettyRunner [url=https://127.0.0.1:41947/collection1]>
[junit4] > at __randomizedtesting.SeedInfo.seed([1063FF0B0248691D:9837C0D1ACB404E5]:0)
[junit4] > at org.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:162)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1532339 INFO (SUITE-PeerSyncReplicationTest-seed#[1063FF0B0248691D]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.PeerSyncReplicationTest_1063FF0B0248691D-001
[junit4] 2> Jan 13, 2017 12:25:07 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {other_tl1=FSTOrd50, range_facet_l_dv=BlockTreeOrds(blocksize=128), rnd_s=Lucene50(blocksize=128), multiDefault=Lucene50(blocksize=128), intDefault=FSTOrd50, a_i1=FSTOrd50, range_facet_l=FSTOrd50, _version_=FSTOrd50, a_t=FSTOrd50, id=BlockTreeOrds(blocksize=128), range_facet_i_dv=FSTOrd50, text=FST50, timestamp=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), range_facet_i_dv=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=781, maxMBSortInHeap=5.411463018468438, sim=RandomSimilarity(queryNorm=false): {}, locale=zh-HK, timezone=Europe/Bucharest
[junit4] 2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 1.8.0_112 (32-bit)/cpus=12,threads=1,free=154295320,total=371957760
[junit4] 2> NOTE: All tests run in this JVM: [TestHighFrequencyDictionaryFactory, TestTrieFacet, TestInitParams, ManagedSchemaRoundRobinCloudTest, CoreAdminRequestStatusTest, DeleteInactiveReplicaTest, DataDrivenBlockJoinTest, TestBlendedInfixSuggestions, ResponseHeaderTest, TestDocTermOrds, SolrCoreMetricManagerTest, TestIntervalFaceting, ShowFileRequestHandlerTest, TestUtils, TestSubQueryTransformerDistrib, TestReversedWildcardFilterFactory, ConnectionReuseTest, SynonymTokenizerTest, TestAddFieldRealTimeGet, CdcrRequestHandlerTest, TestSmileRequest, TestIBSimilarityFactory, AutoCommitTest, HdfsDirectoryFactoryTest, TestChildDocTransformer, BadCopyFieldTest, LeaderInitiatedRecoveryOnShardRestartTest, TestCustomDocTransformer, BasicDistributedZk2Test, MetricsHandlerTest, TestDynamicFieldResource, TestRestManager, CollectionStateFormat2Test, TestPhraseSuggestions, TestMergePolicyConfig, TestImpersonationWithHadoopAuth, TestFieldSortValues, TestCloudPseudoReturnFields, TestGroupingSearch, HighlighterConfigTest, ForceLeaderTest, TestFastOutputStream, TestIndexingPerformance, SolrPluginUtilsTest, HdfsChaosMonkeySafeLeaderTest, TestSolrDeletionPolicy1, TestLeaderInitiatedRecoveryThread, TestNumericTerms32, TestShardHandlerFactory, HLLSerializationTest, MergeStrategyTest, LeaderFailoverAfterPartitionTest, TestHdfsCloudBackupRestore, CoreSorterTest, DistributedQueueTest, TestSolrJ, JSONWriterTest, TemplateUpdateProcessorTest, TestHashQParserPlugin, FacetPivotSmallTest, StatsReloadRaceTest, TestDocSet, TestOverriddenPrefixQueryForCustomFieldType, TestRuleBasedAuthorizationPlugin, TestLuceneMatchVersion, CurrencyFieldXmlFileTest, EchoParamsTest, TestDistributedMissingSort, TestTrackingShardHandlerFactory, ClassificationUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, TlogReplayBufferedWhileIndexingTest, TermVectorComponentDistributedTest, TestStressLiveNodes, CircularListTest, HdfsRecoverLeaseTest, TestDistributedStatsComponentCardinality, TestSolrXml, CheckHdfsIndexTest, TestPHPSerializedResponseWriter, TestReloadDeadlock, TestCollationField, RecoveryZkTest, TestConfigOverlay, TestConfig, OverseerModifyCollectionTest, XmlUpdateRequestHandlerTest, TestSolrFieldCacheMBean, TestHdfsBackupRestoreCore, OutOfBoxZkACLAndCredentialsProvidersTest, TestDistribIDF, TestRequestStatusCollectionAPI, GraphQueryTest, TestRecovery, TestTolerantSearch, TestDocBasedVersionConstraints, DirectUpdateHandlerOptimizeTest, TestSchemalessBufferedUpdates, TestLRUStatsCache, MetricUtilsTest, SuggesterTest, TestFieldTypeCollectionResource, AssignTest, BlockJoinFacetDistribTest, DistributedQueryComponentCustomSortTest, PKIAuthenticationIntegrationTest, BadIndexSchemaTest, UUIDUpdateProcessorFallbackTest, RestartWhileUpdatingTest, LukeRequestHandlerTest, PrimitiveFieldTypeTest, BasicDistributedZkTest, CollectionsAPIDistributedZkTest, LeaderElectionIntegrationTest, ClusterStateUpdateTest, LeaderElectionTest, TestZkChroot, ShardRoutingCustomTest, TestRealTimeGet, TestStressReorder, TestReload, TestCoreContainer, SimpleFacetsTest, ConvertedLegacyTest, TestSort, DirectUpdateHandlerTest, TestIndexSearcher, HighlighterTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, TestCoreDiscovery, SpellCheckCollatorTest, TestFoldingMultitermQuery, DocValuesTest, SolrCoreCheckLockOnStartupTest, TestWordDelimiterFilterFactory, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, TestRemoteStreaming, TestSurroundQueryParser, TestQueryUtils, DirectSolrSpellCheckerTest, DocumentBuilderTest, FieldAnalysisRequestHandlerTest, RegexBoostProcessorTest, TestCSVResponseWriter, JsonLoaderTest, TestComponentsName, SearchHandlerTest, TestJmxMonitoredMap, URLClassifyProcessorTest, ChaosMonkeyNothingIsSafeTest, CleanupOldIndexTest, CollectionsAPISolrJTest, LeaderElectionContextKeyTest, MigrateRouteKeyTest, OverseerRolesTest, OverseerStatusTest, OverseerTaskQueueTest, PeerSyncReplicationTest]
[junit4] Completed [497/678 (3!)] on J2 in 48.76s, 1 test, 1 failure <<< FAILURES!
[...truncated 55229 lines...]