Hello everyone, I am currently working on a solr JIRA and I am encoutering this test failure as well. If I run the single test it can pass. This is my first time to get involved in solr so Iam not sure what should I do if the trunk branch itself might contain potential test failures. Am I suppoed to wait for the fix or just find a previous passed commit for my development? Or is it a badapples test? Thanks!
Best, Zack Liang On 7/21/15, 10:05 PM, "Policeman JenkinServer" <[email protected]> wrote: >Build: http://jenkins.theaphi.de/job/Lucene-Solr-trunk-MacOSX/2534/ >Java: 64bitjdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC > >1 tests failed. >FAILD: org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTes > >Error Message: >Timeout while trying to assert update logs collection=source_collection > >Stack Trace: >java.lang.AssertionError: Tieout while trying to assert update logs @ >collection=source_collection > at >__randomizedsting.SeedInfo.seed([FA6717C1ABDFB670:F20762EDA4D19E7B]:0) > at > >org.apache.solr.cloud.CdcrReplicationDistributedZkTest.assertNumberOfTlogF>iles(CdcrReplicationDistributedZkTest.java:644) > at >orgapache.solr.cloud.CdcrReplicationDistributedZkTest.doTestUpdateLogSync >hronisation(CdcrReplicationDistributedZkTest.java:384) > at >org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTests(CdcrReplica >tionDistributedZkTest.va:50) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native ethod) > at >sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccesorImpl.java: >62) > at >sun.reflect.DelegatingMethodAccessorImpl.invoke(DlegatingMethodAccessorIm >pl.java:43) > at java.lang.reflect.Method.invoke(Method.java:497) at >com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(andomizedRunne >r.java:1627) > at >com.carrotsearch.randomizedtesting.RandmizedRunner$6.evaluate(RandomizedR >unner.java:836) > at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedR >unner.java:872) > at >com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedR >unner.java:886) > at >org.apache.solr.BasestributedSearchTestCase$ShardsRepeatRule$ShardsFixed >Statement.allStatement(BaseDistributedSearchTestCase.java:963) > at >org.apache.solrBaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStat >ment.evaluate(BaseDistributedSearchTestCase.java:938) > at >com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.eva >luate(SystemPropertiesRestoreRule.java:57) > at >org.apache.lucene.util.TestRuleSetupTearwnChained$1.evaluate(TestRuleSet >upTeardownChained.java:50) > a >org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforef >terRule.java:46) > at >org.apache.lucene.util.TesRuleThreadAndTestName$1.evaluate(TestRuleThread >AndTestName.java:49) > at >org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleI >gnoreAfterMaxFailures.java:65) > at >org.apache.lucene.util.TestRuleMrkFailure$1.evaluate(TestRuleMarkFailure.java:48) > at >com.carrotsearch.randomizedtesting.rules.StatemetAdapter.evaluate(Stateme >ntAdapter.java:36) > at >com.carrotsearch.randoizedtesting.ThreadLeakControl$StatementRunner.run(T >hreadeakControl.java:365) > at >com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(Th >readLeakControl.java:798) > at >com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeak >Control.jav458) > at >com.carrotsearch.randomizedtesting.RandomizedRunner.unSingleTest(Randomiz >edRunner.java:845) > at >com.carrotsearch.randomizetesting.RandomizedRunner$3.evaluate(RandomizedR >unner.java:47) > at >com.carrotsearch.randomizedtesting.RandomizedRunner$4evaluate(RandomizedR >unner.java:781) > at >co.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(andomizedR >unner.java:792) > at >com.carrotsearch.randomizedtesting.rles.StatementAdapter.evaluate(Stateme >ntAdapter.java:36) > at >com.carrotsearch.randomidtesting.rules.SystemPropertiesRestoreRule$1.eva >luate(SystemPrpertiesRestoreRule.java:57) > at >org.apache.lucene.util.AbstractBeforeAftrRule$1.evaluate(AbstractBeforeAf >terRule.java:46) > at >com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stateme >tAdapter.java:36) > at >org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClas >sName.java:42) > at >com.carrotsear.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRu >le$1evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) > at >com.carrotsearh.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRu >le$1.evaluate(NoSdowingOrOverridesOnMethodsRule.java:39) > at >com.carrotsearch.randomizetesting.rules.StatementAdapter.evaluate(Stateme >ntAdapter.java:36) > at >com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Sateme >ntAdapter.java:36) > at >com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stateme >ntAdapter.java:36) > at >org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAsser >tionsReqred.java:54) > at >org.apache.lucene.util.TestRuleMarkFailure$1.evaluateTestRuleMarkFailure. >java:48) > at >org.apache.luceneutil.TestRuleIgnoreAfterMaFailures$1.evaluate(TestRuleI >gnoreAfterMaxFailures.java:65) > at >org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreT >estSuites.java:55) > at >com.carrotsearch.randomizedtesting.rules.StatemetAdapter.evaluate(Stateme >ntAdapter.java:36) > at >com.carrotsearch.randomizedtestinghreadLeakControl$StatementRunner.run(T >hreadLeakControl.java:36) > at java.lang.Thread.run(Thread.java:745) >Caused by: java.lang.AssertioError: Number of tlogs on leader: 70 is >superio to: 50. > at org.junit.Assert.fail(Assert.java:93) > at org.junit.Assert.assertTruAssert.java:43) > at >org.apache.solr.cloud.CdcrReplicationDistibutedZkTest._assertNumberOfTlog >Files(CdcrReplicationDistributedZkTest.jaa:671) > at >org.apache.solr.cloud.CdcrReplicationDistributedZkTest.ssertNumberOfTlogF >iles(CdcrReplicationDistributedZkTest.va:634) > ... 43 more > > > > >Build Log: >[...truncated 10428 lnes...] > [junit4] Suite: org.apache.solr.cloud.CdcrReplicationDistributeZkTest > [junit4] 2> Creating dataDir: >/Users/jenkins/workspace/Lucene-Solrrunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrRplicationDistributedZkTest_FA6717C1ABDFB670-001 >/init-core-data-001 > [jnit4] 2> 1475810 INFO >(SUITE-CdcrReplicationDistributedZkTest-seed#[FA6717C1AFB670]-worker) [ > ] o.a.s.SolrTestCaseJ4 Randomized ssl (true and clientAuth (false) > [junit4] 2> 1475810 INFO >(SUITE-CdcrReplicatonDistributedZkTest-seed#[FA6717C1ABDFB670]-worker) [ > ] o.a.s.BaseDistributeearchTestCase Setting hostContext system >property: / > [junit] 2> 1475813 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[F6717C1ABDFB670]) [ > ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER > [junit 2> 1475814 INFO (Thread-3290) [ ] o.a.s.c.ZkTestServer >cient port:0.0.0.0/0.0.0.0:0 > [junit4] 2> 1475814 INFO (Thread-3290) [ ] o.a.s.c.ZkTestServer >Starting server [junit4] 2> 1475915 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.ZkTestServer start zk server on port:58151 > [junit4] 2> 1475943 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.cbstractZkTestCase put >/Users/jenkins/workspace/Lucene-Solr-trun-MacOSX/solr/core/src/test-files >/solr/collection1/conf/solrconfig-cdcr.xm to >/configs/conf1/solrconfig.xml > [junit4] > 1475949 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1DFB670]) [ > ] o.a.s.c.AbstractZkTestCase put >/Users/jenkins/orkspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files >/solr/collectio1/conf/schema15.xml to /configs/conf1/schema.xml > [jnit4] 2> 1475954 INFO >(TEST-CdcrReplicationDistributedZkTest.doTes-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.AbstractZkTestCase > put>/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files>/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml > to >/confis/conf1/solrconfig.snippet.randomindexconfig.xml > [junit4] 2> 1475958 INFO >(TEST-CdcrReplicionDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o..s.c.AbstractZkTestCase put >/Users/jenkins/workspace/Lucene-Solr-trunk-MacSX/solr/core/src/test-files >/solr/collection1/conf/stopwords.txt to /confi/conf1/stopwords.txt > [junit4] 2> 1475962 INFO >(TEST-CdcrRplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.cAbstractZkTestCase put >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/sr/core/src/test-files >/solr/collection1/conf/protwords.txt to /onfigs/conf1/protwords.txt > [junit4] 2> 1475967 INFO >(TEST-CdcrReplictionDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.AbstractZkTestCase put >/Users/jenkins/workspace/Lucen-Solr-trunk-MacOSX/solr/core/src/test-files >/solr/collection1/conf/curency.xml to /configs/conf1/currency.xml > [junt4] 2> 1475971 INFO >(TEST-CdcrReplicationDistributedZTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.AbstractZkTestCase put >/Users/jenki/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files >/slr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml > [unit4] 2> 1475975 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.AbstractZkTestCase ut >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files >/solr/collection1/conf/open-exchange-rates.json to >/confs/conf1/open-exchange-rates.json > [junit4] 2> 1475980 INFO (TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.AbstractZkTestCase put >/Users/jenkins/workspace/Lucene-Solr-trunkacOSX/solr/core/src/test-files >/solr/collection1/conf/mapping-IOLatin1Accent.txt to >/configs/conf1/mapping-ISOLatin1Accent.txt > [junit] 2> 1475984 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.s.c.AbstractZkTestCase put >/Users/jenkins/workspace/Lucene-Sol-trunk-MacOSX/solr/core/src/test-files >/solr/collection1/conf/old_synonymstxt to /configs/conf1/old_synonyms.txt > [junit4] 2> 1475989 INFO >(TES-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] a.s.c.AbstractZkTestCase put >/Users/jenkins/workspace/Lucene-Sor-trunk-MacOSX/solr/core/src/test-files >/solr/collection1/conf/synonyms.tx to /configs/conf1/synonyms.txt > [junit4] 2> 1476000 INFO >(TET-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1AFB670]) [ > ] o.a.s.SolrTestCaseJ4 ###Starting doTests > [juit4] 2> 1476351 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed[FA6717C1ABDFB670]) [ > ] o.a.s.SolrTestCaseJ4 Writing core.properties file to >/Users/jenkinworkspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jety-001/cores/collection1 > [junit4] 2> 1476354 INFO >(TEST-CdrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] e.j.s.Server jetty-9.2.11.v20150529 > [junit4] 2> 1476358 INO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) > ] o.e.j.s.h.ContextHandler Started >o.e.j.s.ServletontextHandler@39ae247e{/,null,AVAILABLE} > [junit4] 2> 1476375NFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA67171ABDFB670]) [ > ] o.e.j.s.ServerConnector Started >ServerConnector@cbbd36{SSL-http/1.1}{127.0.0.1:58155} > [junit4] 2> 1476376 INFO >(TEST-CcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.e.j.Server Started @1482014ms > [junit4] 2> 1476376 INFO >(TESTCdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] oa.s.c.s.e.JettySolrRunner Jetty properties: {shards=shard1, >hostContext=/, hostPort=58155, >coRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/olr/b >uild/solr-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTst_FA >6717C1ABDFB670-001/jetty-001/cores} > [junit4] 2> 1476378 INFO >(TEST-CdcrReplicatioistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.ss.SolrDispatchFilter SolrDispatchFilter.init(): >sun.misc.Launcher$AppClassoader@74a14482 > [junit4] 2> 1476378 INFO >(TEST-CdcrReplicationDistibutedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.SolrResorceLoader new SolrResourceLoader for directory: >'/Users/jenkins/workspace/Lucene-Solr-trunkacOSX/solr/build/solr-core/te >st/J1/temp/solr.cloud.CdcrReplicaionDistributedZkTest_FA6717C1ABDFB670-00 >1/jetty-001/' > [junit4] 2> 176419 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1BDFB670]) [ > ] o.a.s.c.SolrXmlConfig ading container configuration from >/Users/jenkins/workspace/Lucne-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrRepicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-001/solr.xml > [junit4] 2> 1476435 INFO >(TEST-CdcrReplicationDistributZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.CoresLoctor Config-defined core root directory: >/Users/jenkins/workspace/Lucene-Sor-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrRepliationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-001/cores > [junit4] 2> 1476435 INFO >(TEST-CdcrReplicationDistributedZkTet.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.CoreContainer New CoreCotainer 1924536609 > [junit4] 2> 1476435 INFO >(TEST-CdcrReplicationDisributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.CoreCtainer Loading cores into CoreContainer >[instanceDir=/Users/jenins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/ >solr-core/test/J1/temp/olr.cloud.CdcrReplicationDistributedZkTest_FA6717C >1ABDFB670-001/jetty-00/] > [junit4] 2> 1476435 INFO >(TEST-CdcrReplicaonDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.as.c.CoreContainer loading shared library: >/Users/jenkins/workspace/Lucene-olr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDtributedZkTest_FA6717C1ABDFB670-001 >/jetty-001/lib > [junit4] 2> 1476436 WARN >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA617C1ABDFB670]) [ > ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to d to >classloader: lib (resolved as: >/Users/jenkins/workspace/Lucene-Solr-runk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jety-001/lib). > [junit4] 2> 1476451 INFO >(TEST-CdcrRepicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.h.c.HttpShardHandlerFactory creed with socketTimeout : >90000,urlScheme : ,connTimeout : 15000,maxConnectinsPerHost : >20,maxConnections : 10000,corePoolSize : 0,maximmPoolSize : >2147483647,maxThreadIdleTime : 5,sizeOfQuee : -1,fairnessPolicy : >false,useRetries : false, > [junit] 2> 1476457 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.u.UpdateShardHandler Creatg UpdateShardHandler HTTP client >with params: socketTimeout=340000&connTimout=45000&retry=true > [junit4] 2> 1476459 INFO >(TEST-CdrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDB670]) [ > ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggeFactory > [junit4] 2> 1476459 INFO TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA67171ABDFB670]) [ > ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] > [junit4] 2> 1476459 INFO >(TEST-CdcrRepcationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.Zkontainer Zookeeper client=127.0.0.1:58151/solr > [junit4] 2> 1476460 INFO >(TEST-CdcrReplicationDistributedZkTestdoTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.ZkController zkHost includes chroot > [junit4] 2>476505 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ADFB670]) >[n:127.0.0.1:58155_ ] o.a.s.c.ZkController Regiser node as live in >ZooKeeper:/live_nodes/127.0.0.1:5815_ > [junit4] 2> 1476525 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670])[n:127.0.0.1:58155_ > ] o.a.s.c.Overseer Overseer (id=null) closing > [jnit4] 2> 1476526 INFO >(TEST-CdcrReplicationDistributedZkTet.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58155_ ] o.a.s.c.LeaderElector Joined leadership election >with path: >/overseer_elect/election/942106022001372-127.0.0.1:58155_-n_0000000000 > [junit4] 2> 1476528 INFO >(TEST-CdcrRplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >n:127.0.0.1:58155_ ] o.a.s.c.OverseerElectionContextI am going to be >the leader 127.0.0.1:58155_ > [junit4] 2> 1476531 INFO >(TEST-CdcrReplationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58155 ] o.a.s.c.Overseer Overseer >(id=94210602200137732-127.0..1:58155_-n_0000000000) starting > [junit4] 2> 147651 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58155_ ].a.s.c.OverseerAutoReplicaFailoverThread >Starting OverseerAutoReplicaFailoerThread >autoReplicaFailoverWorkLoopDelay=10000 >autoReplicaailoverWaitAfterExpiration=30000 >autoReplicaFailoverBaNodeExpiration=60000 > [junit4] 2> 1476552 INFO >(OveseerCollectionProcessor-94210602200137732-127.0.0.1:58155_-n_00000000 >00) [127.0.0.1:58155_ ] o.a.s.c.OverseerCollectionProcessor Process >currnt queue of collection creations > [junit4] 2> 1476553 INFO >(OverseeStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.Overseer Starting to work on the main >queue > [junit4] 2> 1476561 INFO >(TEST-CdcrReplicationDistribedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:5855_ ] o.a.s.c.CoreContainer No authentication plugin >used. > [junt4] 2> 1476563 INFO >(TEST-CdcrReplicationDistributedZkTest.doTestseed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58155_ ] o.a.s.c.oreContainer Security conf doesn't >exist. Skipping setup for authorizaion module. > [junit4] 2> 1476567 INFO >(TEST-CdcrReplicationDistributedZkTestoTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58155_ ] o.a.s.c.Coresocator Looking for core >definitions underneath >/Users/jenkins/workspaceLucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-001/cores > [junit4] 2> 1476568 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58155_ ] o.a.s.c.SolrCore Created CoreDescriptor: >{name=collection1, cong=solrconfig.xml, transient=false, >schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, >instanceDir=/Users/enkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/s olr-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA677C1 >ABDFB670-001/jetty-001/cores/collection1, collection=tmp_collection, >absoteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/bui >ld/sor-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTet_FA67 >17C1ABDFB670-001/jetty-001/cores/collection1/, oreNodeName=, >dataDir=data/, shard=} > [junit4] 2> 1476569INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6711ABDFB670]) >[n:127.0.0.1:58155_ ] o.a.s.c.CoresLocator Found core colletion1 in >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/slr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplictionDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-001/cores/collection1/ > [junit4] 2> 76569 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABFB670]) >[n:127.0.0.1:58155_ ] o.a.s.c.CoresLocator Found core definitions > [junit4] 2> 1476570 INFO >(TESTCdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0:58155_ ] o.a.s.s.SolrDispatchFilter >user.dir=/Users/jenkins/workspace/ucene-Solr-trunk-MacOSX/solr/build/solr >-core/test/J1 > [jnit4] 2> 1476571 INFO >(TEST-CdcrReplicationDistributdZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58155_ ] o.a.s.sSolrDispatchFilter >SolrDispatchFilter.init() do > [junit4] 2> 1476571 INFO >(coreLoadExecutor-4271-thead-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collecton x:collection1] >o.a.s.c.ZkController publishing state=down > [junit4] 2> 76571 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:5815_) >[n:127.0.0.1:58155_ c:tmp_collection x:collection1] >o.a.s.c.ZkContoller numShards not found on descriptor - reading it from >system property > [junit4] 2> 1476572 INFO >(zkCallback-1229-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ ] o.a.s.c.DistributedQueue NodeChildrenChanged >fired on path /overseer/queue state SyncConnected > [junit4] 2> 1476572 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection x:collection1] >o.a.s.c.ZkController look for our core node name > [junit4] 2> 1476574NFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.Overseer processMessage: quueSize: 1, >message = { > [junit4] 2> "core":"collection1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:58155";, > [junit4] 2> "node_name":"127.0.0.1:58155_", > [junit4] 2> "numShards":"2", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} current state version: 0 > [junit4] 2> 1476575 INFO >(OverseerStateUpdate-942106022137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.o.RplicaMutator Update state >numShards=2 message={ > [junit] 2> "core":"collection1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:58155";, > [junit4] 2> "node_name":"127.0.0.1:58155_", > [junit4] 2> "numShards":"2", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junt4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"ate"} > [junit4] 2> 1476576 INFO >(OverseerStateUpdate-9421060220013773-127.0.0.1:58155_-n_0000000000) >[n:1270.0.1:58155_ ] o.a.s.c.o.ClusterStateMutator building a new >cName: tmp_collection > junit4] 2> 1476576 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.158155_-n_0000000000) >[n:127.0.0.1:5815_ ] o.a.s.c.o.ReplicaMutator Assigning new node to >shard shard=shard2 > [junit4] 2> 1477574 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:5815_) >[n:127.0.0.1:58155_ c:tmp_collection x:collection1] >o.a.s.c.ZkCtroller waiting to find shard id in clusterstate for >collection1 > [juni4] 2> 1477574 INFO >(coreLoadExecutor-4271-thread-1-processing-n:17.0.0.1:58155_) >[n:127.0.0.1:5855_ c:tmp_collection x:collection1] >o.a.s.c.ZkController Check for collection zkNode:tmp_collection > [junit4] 2> 1477576 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ cmp_collection x:collection1] >o.a.s.c.ZkController Creating collection inZooKeeper:tmp_collection > [junit4] 2> 1477576 INFO >(coeLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:555_ c:tmp_collection x:collection1] >o.a.s.c.ZkController Looking for colection configName > [junit4] 2> 1477577 INFO >(coreLoadExecutor-427-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection x:collection1] >o.a.s.c.ZkController Only one config set found in zk - using it:conf1 > [junit4] 2> 1477582 INFO coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.158155_ c:tmp_collection x:collection1] >o.a.s.c.SolrResourceLoaer new SolrResourceLoader for directory: >'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/te >st/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-00 >1/jetty-00cores/collection1/' > [junit4] 2> 1477599 INFO >(coreLoadExecutor-4271-hread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tp_collection x:collection1] o.a.s.c.Config >loaded config solrconfig.xml with version 0 > [junit4] 2> 1477606 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:585_ c:tmp_collection x:collection1] o.a.s.c.SolrConfig >current version ofrequestparams : -1 > [junit4] 2> 1477608 INFO >(coreLoadExecutor-4271-thread-1-proceing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection x:collectio1] o.a.s.c.SolrConfig >Using Lucene MatchVersion: 6.0.0 > [unit4] 2> 1477612 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection x:collection1] o.a.s.c.Config >Loaded SolrConfig: solrconfig.xml > [junit4] 21477615 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_ >[n:127.0.0.1:58155_ c:tmp_collection x:collectio1] o.a.s.s.IndexSchema >Reading Solr Schema from /configs/conf1/schema.xml > [junit4] 2> 1477617 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_coection x:collection1] o.a.s.s.IndexSchema >[collection1] Schema name=test> [junit4] 2> 1477713 INFO >(coreLoadExecutor-4271-thread-1-processingn:127.0.0.1:58155_) >[n:127.0.0.:58155_ c:tmp_collection x:collection1] o.a.s.s.IndexSchema >default search field in schema is text > [junit4] 2> 1477714 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:7.0.0.1:58155_ c:tmp_collection x:collection1] o.a.s.s.IndexSchema >uniqu key field: id > [junit4] 2> 1477714 INFO >(coreLoadExecutor-4271thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tp_collection x:collection1] >o.a.s.s.FileExchangeRateProvide Reloading exchange rates from file >currency.xml > [junit4] 2> 477719 INFO >(coreLoadExecutor-4271-thread-1-processing-n:7.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection x:collection1] >o.as.s.FileExchangeRateProvider Reloading exchange rates from file >currencyxml > [junit4] 2> 1477777 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:5815) >[n:127.0.0.1:58155_ c:tmp_collection x:collection1] >o.a.s.c.ConfigSetroperties Did not find ConfigSet properties > [junit4] 2> org.apache.solr.core.SolrResourceNotFoundEeption: >Can't find resource 'configsetprops.json' in classpath or >'/confis/conf1', >cwd=/Users/jenkins/workspace/Lucene-Solr-trun-MacOSX/solr/build/solr-core >/test/J1 > [junit4] 2> at >org.apae.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoad >er.java:9) > [junit4] 2> at >org.apache.solr.core.ConfigSetProperties.readFromResourceLoader(ConfietP >roperties.java:49) > [junit4] 2> at >org.apache.solr.core.ConfigStService.createConfigSetProperties(ConfigSetS >ervice.java:114) > [junit4] 2>at >org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java76) > [junit4] 2> at >org.apache.solr.core.CoreContainer.create(CorContainer.java:668) > [junit4] 2> at >org.apache.solr.core.CoreConiner$1.call(CoreContainer.java:397) > [junit4] 2> at >org.apache.sol.core.CoreContainer$1.call(CoreContainer.java:388) > [junit4] 2> at >java.util.concurrent.FutureTask.run(FutureTask.java:266) > [junit4] 2> at >org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run( >Executortil.java:156) > [junit4] 2> at >java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java: >1142) > [junit4] 2> at >java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java >:617) > [junit4] 2> a java.lang.Thread.run(Thrad.java:745) > [junit4] 2> 1477777 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection x:collection1] >o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration >from collection tmp_collection > [junit4] 2> 1477777 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory > [junit4] 2> 1477778 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at >[/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/te >st/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-00 >1/jetty-001/cores/collection1/], dataDir=[null] > [junit4] 2> 1477778 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to >JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a3bc8e8 > [junit4] 2> 1477778 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.CachingDirectoryFactory return new directory for >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-001/cores/collection1/data > [junit4] 2> 1477778 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SolrCore New index directory detected: old=null >new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core >/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670 >-001/jetty-001/cores/collection1/data/index/ > [junit4] 2> 1477778 WARN >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SolrCore [collection1] Solr index directory >'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/te >st/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-00 >1/jetty-001/cores/collection1/data/index' doesn't exist. Creating new >index... > [junit4] 2> 1477779 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.CachingDirectoryFactory return new directory for >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-001/cores/colltion1/data/index > [junit4] 2> 1477779 INFO >(coreLoadExecutor-471-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_ollection s:shard2 r:core_node1 x:collection1] >o.a.s.u.andomMergePolicy RandomMergePolicy wrapping class >org.apache.lucene.index.TieredMergePolicy: [TieredMrgePolicy: >maxMergeAtOnce=46, maxMergeAtOnceExplicit=47, >maxMergedSegmentMB83.1484375, floorSegmentMB=0.607421875, >forceMergeDeletesPctAllowed=3.220248038000804, segmentsPerTier=24.0, >maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 > [junit4 2> 1477779 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:colection1] >o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 > [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5fb00b23 >lockFactry=org.apache.lucene.store.SingleInstanceLockFactory@2379c13f),se >gFN=segments_1,generation=1} > [junit4] 2> 1477779 INFO >(creLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SolrCore newest comit generation = 1 > [junit4] 2> 1477780 INFO >(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore >Looking for old index directories to cleanup for core collection1 in >Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/uild/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-001/cores/collection1/data/ > [junit4] 2>1477781 WARN >(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.DirectoryFactory >/Users/jenkins/workace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.clod.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty001/cores/collection1/data/ does not point to a valid data >directory; skipping clean-up of old index directories. > [junit4] 2>1477784 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.u.p.UpdateRequestProcessorChain creating >updateRequestProcessorChain "cdcr-processor-chain" > [junit4] 2> 1477784 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, >creating implicit default > [junit4] 2> 1477789 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 1477790 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 1477791 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 1477792 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 1477797 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.RequestHandlers Registered paths: >/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/se >gments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/ >admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/cdcr >,/update,/admin/file > [junit4] 2> 1477797 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SolrCore Using default statsCache cache: >org.apache.solr.search.stats.LocalStatsCache > [junit4] 2> 1477798 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.u.UpdateHandler Using UpdateLog implementation: >org.apache.solr.update.CdcrUpdateLog > [junit4] 2> 1477798 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH >numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 > [junit4] 2> 1477799 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SolrCore Hard AutoCommit: disabled > [junit4] 2> 1477799 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SolrCore Soft AutoCommit: disabled > [junit4] 2> 1477799 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class >org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: >minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, >maxMergeSizeForForcedMerge=9223372036854775807, >calibrateSizeByDeletes=false, maxMergeDocs=2147483647, >maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] > [junit4] 2> 1477799 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 > [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5fb00b23 >lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2379c13f),se >gFN=segments_1,generation=1} > [junit4] 2> 1477799 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SolrCore newest commit generation = 1 > [junit4] 2> 1477799 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.s.SolrIndexSearcher Opening Searcher@7cf0c392[collection1] main > [junit4] 2> 1477801 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the >RestManager with znodeBase: /configs/conf1 > [junit4] 2> 1477802 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with >znodeBase: /configs/conf1 > [junit4] 2> 1477803 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.r.RestManager Initializing RestManager with initArgs: {} > [junit4] 2> 1477803 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using >ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 1477804 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.r.ManagedResourceStorage No data found for znode >/configs/conf1/_rest_managed.json > [junit4] 2> 1477804 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json >using ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 1477804 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.r.RestManager Initializing 0 registered ManagedResources > [junit4] 2> 1477804 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.h.ReplicationHandler Commits will be reserved for 10000 > [junit4] 2> 1477812 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.h.CdcrBufferStateManager Created znode >/collections/tmp_collection/cdcr/state/buffer > [junit4] 2> 1477816 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.h.CdcrProcessStateManager Created znode >/collections/tmp_collection/cdcr/state/process > [junit4] 2> 1477819 INFO >(searcherExecutor-4272-thread-1-processing-n:127.0.0.1:58155_ >x:collection1 s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore >[collection1] Registered new searcher Searcher@7cf0c392[collection1] >main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1477819 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.u.UpdateLog Looking up max value of version field to seed version >buckets > [junit4] 2> 1477819 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 >version buckets from index > [junit4] 2> 1477819 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.u.VersionInfo No terms found for _version_, cannot seed version >bucket highest value from index > [junit4] 2> 1477819 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.u.UpdateLog Could not find max version in index or recent updates, >using new clock 1507369637301452800 > [junit4] 2> 1477821 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version >1507369637301452800 > [junit4] 2> 1477821 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.ZkController watch zkdir /configs/conf1 > [junit4] 2> 1477822 INFO >(coreLoadExecutor-4271-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.CoreContainer registering core: collection1 > [junit4] 2> 1477823 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.ZkController Register replica - core:collection1 >address:https://127.0.0.1:58155 collection:tmp_collection shard:shard2 > [junit4] 2> 1477832 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.LeaderElector Joined leadership election with path: >/collections/tmp_collection/leader_elect/shard2/election/94210602200137732 >-core_node1-n_0000000000 > [junit4] 2> 1477834 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.ShardLeaderElectionContext Running the leader process for shard >shard2 > [junit4] 2> 1477837 INFO >(zkCallback-1229-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ ] o.a.s.c.DistributedQueue NodeChildrenChanged >fired on path /overseer/queue state SyncConnected > [junit4] 2> 1477838 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. > [junit4] 2> 1477838 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync > [junit4] 2> 1477839 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:58155/collection1/ > [junit4] 2> 1477839 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SyncStrategy Sync Success - now sync replicas to me > [junit4] 2> 1477839 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.SyncStrategy https://127.0.0.1:58155/collection1/ has no replicas > [junit4] 2> 1477839 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.ShardLeaderElectionContext I am the new leader: >https://127.0.0.1:58155/collection1/ shard2 > [junit4] 2> 1477839 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.Overseer processMessage: queueSize: 1, >message = { > [junit4] 2> "operation":"leader", > [junit4] 2> "shard":"shard2", > [junit4] 2> "collection":"tmp_collection"} current state version: 1 > [junit4] 2> 1477845 INFO >(zkCallback-1229-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ ] o.a.s.h.CdcrProcessStateManager Received new >leader state @ tmp_collection:shard2 > [junit4] 2> 1477849 INFO >(zkCallback-1229-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ ] o.a.s.c.DistributedQueue NodeChildrenChanged >fired on path /overseer/queue state SyncConnected > [junit4] 2> 1477851 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.Overseer processMessage: queueSize: 1, >message = { > [junit4] 2> "operation":"leader", > [junit4] 2> "shard":"shard2", > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "base_url":"https://127.0.0.1:58155";, > [junit4] 2> "core":"collection1", > [junit4] 2> "state":"active"} current state version: 1 > [junit4] 2> 1478004 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.ZkController We are https://127.0.0.1:58155/collection1/ and >leader is https://127.0.0.1:58155/collection1/ > [junit4] 2> 1478004 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.ZkController No LogReplay needed for core=collection1 >baseURL=https://127.0.0.1:58155 > [junit4] 2> 1478004 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.ZkController I am the leader, no recovery necessary > [junit4] 2> 1478005 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.ZkController publishing state=active > [junit4] 2> 1478005 INFO >(coreZkRegister-4265-thread-1-processing-n:127.0.0.1:58155_ x:collection1 >s:shard2 c:tmp_collection r:core_node1) [n:127.0.0.1:58155_ >c:tmp_collection s:shard2 r:core_node1 x:collection1] >o.a.s.c.ZkController numShards not found on descriptor - reading it from >system property > [junit4] 2> 1478007 INFO >(zkCallback-1229-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ ] o.a.s.c.DistributedQueue NodeChildrenChanged >fired on path /overseer/queue state SyncConnected > [junit4] 2> 1478008 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.Overseer processMessage: queueSize: 1, >message = { > [junit4] 2> "core":"collection1", > [junit4] 2> "core_node_name":"core_node1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:58155";, > [junit4] 2> "node_name":"127.0.0.1:58155_", > [junit4] 2> "numShards":"2", > [junit4] 2> "state":"active", > [junit4] 2> "shard":"shard2", > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} current state version: 2 > [junit4] 2> 1478010 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.o.ReplicaMutator Update state >numShards=2 message={ > [junit4] 2> "core":"collection1", > [junit4] 2> "core_node_name":"core_node1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:58155";, > [junit4] 2> "node_name":"127.0.0.1:58155_", > [junit4] 2> "numShards":"2", > [junit4] 2> "state":"active", > [junit4] 2> "shard":"shard2", > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} > [junit4] 2> 1478440 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.SolrTestCaseJ4 Writing core.properties file to >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-002/cores/collection1 > [junit4] 2> 1478443 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.e.j.s.Server jetty-9.2.11.v20150529 > [junit4] 2> 1478446 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.e.j.s.h.ContextHandler Started >o.e.j.s.ServletContextHandler@1584685b{/,null,AVAILABLE} > [junit4] 2> 1478450 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.e.j.s.ServerConnector Started >ServerConnector@5f010186{SSL-http/1.1}{127.0.0.1:58162} > [junit4] 2> 1478450 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.e.j.s.Server Started @1484087ms > [junit4] 2> 1478450 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {shards=shard2, >hostContext=/, hostPort=58162, >coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/b >uild/solr-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA >6717C1ABDFB670-001/jetty-002/cores} > [junit4] 2> 1478453 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): >sun.misc.Launcher$AppClassLoader@74a14482 > [junit4] 2> 1478453 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: >'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/te >st/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-00 >1/jetty-002/' > [junit4] 2> 1478485 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.SolrXmlConfig Loading container configuration from >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-002/solr.xml > [junit4] 2> 1478493 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.CoresLocator Config-defined core root directory: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-002/cores > [junit4] 2> 1478494 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.CoreContainer New CoreContainer 1822857970 > [junit4] 2> 1478494 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.CoreContainer Loading cores into CoreContainer >[instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/ >solr-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C >1ABDFB670-001/jetty-002/] > [junit4] 2> 1478494 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.CoreContainer loading shared library: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-002/lib > [junit4] 2> 1478494 WARN >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to >classloader: lib (resolved as: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-002/lib). > [junit4] 2> 1478503 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : >90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : >20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : >2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : >false,useRetries : false, > [junit4] 2> 1478508 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client >with params: socketTimeout=340000&connTimeout=45000&retry=true > [junit4] 2> 1478510 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory > [junit4] 2> 1478510 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.l.LogWatcher Registering Log Listener [Log4j >(org.slf4j.impl.Log4jLoggerFactory)] > [junit4] 2> 1478510 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58151/solr > [junit4] 2> 1478510 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.ZkController zkHost includes chroot > [junit4] 2> 1479542 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.c.ZkController Register node as live in >ZooKeeper:/live_nodes/127.0.0.1:58162_ > [junit4] 2> 1479548 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.c.Overseer Overseer (id=null) closing > [junit4] 2> 1479549 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.c.LeaderElector Joined leadership election >with path: >/overseer_elect/election/94210602200137734-127.0.0.1:58162_-n_0000000001 > [junit4] 2> 1479550 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.c.LeaderElector Watching path >/overseer_elect/election/94210602200137732-127.0.0.1:58155_-n_0000000000 >to know if I could be the leader > [junit4] 2> 1479552 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.c.CoreContainer No authentication plugin >used. > [junit4] 2> 1479553 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.c.CoreContainer Security conf doesn't >exist. Skipping setup for authorization module. > [junit4] 2> 1479554 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.c.CoresLocator Looking for core >definitions underneath >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-002/cores > [junit4] 2> 1479555 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.c.SolrCore Created CoreDescriptor: >{name=collection1, config=solrconfig.xml, transient=false, >schema=schema.xml, loadOnStartup=true, >configSetProperties=configsetprops.json, >instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/s >olr-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1 >ABDFB670-001/jetty-002/cores/collection1, collection=tmp_collection, >absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/bui >ld/solr-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA67 >17C1ABDFB670-001/jetty-002/cores/collection1/, coreNodeName=, >dataDir=data/, shard=} > [junit4] 2> 1479555 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.c.CoresLocator Found core collection1 in >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-002/cores/collection1/ > [junit4] 2> 1479555 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.c.CoresLocator Found 1 core definitions > [junit4] 2> 1479558 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.s.SolrDispatchFilter >user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr >-core/test/J1 > [junit4] 2> 1479558 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58162_ ] o.a.s.s.SolrDispatchFilter >SolrDispatchFilter.init() done > [junit4] 2> 1479558 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] >o.a.s.c.ZkController publishing state=down > [junit4] 2> 1479558 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] >o.a.s.c.ZkController numShards not found on descriptor - reading it from >system property > [junit4] 2> 1479559 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] >o.a.s.c.ZkController look for our core node name > [junit4] 2> 1479560 INFO >(zkCallback-1229-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ ] o.a.s.c.DistributedQueue NodeChildrenChanged >fired on path /overseer/queue state SyncConnected > [junit4] 2> 1479561 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.Overseer processMessage: queueSize: 1, >message = { > [junit4] 2> "core":"collection1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:58162";, > [junit4] 2> "node_name":"127.0.0.1:58162_", > [junit4] 2> "numShards":"2", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} current state version: 3 > [junit4] 2> 1479563 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.o.ReplicaMutator Update state >numShards=2 message={ > [junit4] 2> "core":"collection1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:58162";, > [junit4] 2> "node_name":"127.0.0.1:58162_", > [junit4] 2> "numShards":"2", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} > [junit4] 2> 1479563 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.o.ReplicaMutator Collection already >exists with numShards=2 > [junit4] 2> 1479563 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.o.ReplicaMutator Assigning new node to >shard shard=shard1 > [junit4] 2> 1480561 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] >o.a.s.c.ZkController waiting to find shard id in clusterstate for >collection1 > [junit4] 2> 1480562 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] >o.a.s.c.ZkController Check for collection zkNode:tmp_collection > [junit4] 2> 1480563 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] >o.a.s.c.ZkController Collection zkNode exists > [junit4] 2> 1480565 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] >o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: >'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/te >st/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-00 >1/jetty-002/cores/collection1/' > [junit4] 2> 1480589 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] o.a.s.c.Config >loaded config solrconfig.xml with version 0 > [junit4] 2> 1480599 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] o.a.s.c.SolrConfig >current version of requestparams : -1 > [junit4] 2> 1480602 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] o.a.s.c.SolrConfig >Using Lucene MatchVersion: 6.0.0 > [junit4] 2> 1480623 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] o.a.s.c.Config >Loaded SolrConfig: solrconfig.xml > [junit4] 2> 1480626 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] o.a.s.s.IndexSchema >Reading Solr Schema from /configs/conf1/schema.xml > [junit4] 2> 1480630 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] o.a.s.s.IndexSchema >[collection1] Schema name=test > [junit4] 2> 1480698 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] o.a.s.s.IndexSchema >default search field in schema is text > [junit4] 2> 1480698 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] o.a.s.s.IndexSchema >unique key field: id > [junit4] 2> 1480699 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] >o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file >currency.xml > [junit4] 2> 1480702 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] >o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file >currency.xml > [junit4] 2> 1480755 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] >o.a.s.c.ConfigSetProperties Did not find ConfigSet properties > [junit4] 2> org.apache.solr.core.SolrResourceNotFoundException: >Can't find resource 'configsetprops.json' in classpath or >'/configs/conf1', >cwd=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core >/test/J1 > [junit4] 2> at >org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoad >er.java:99) > [junit4] 2> at >org.apache.solr.core.ConfigSetProperties.readFromResourceLoader(ConfigSetP >roperties.java:49) > [junit4] 2> at >org.apache.solr.core.ConfigSetService.createConfigSetProperties(ConfigSetS >ervice.java:114) > [junit4] 2> at >org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:76) > [junit4] 2> at >org.apache.solr.core.CoreContainer.create(CoreContainer.java:668) > [junit4] 2> at >org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:397) > [junit4] 2> at >org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:388) > [junit4] 2> at >java.util.concurrent.FutureTask.run(FutureTask.java:266) > [junit4] 2> at >org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run( >ExecutorUtil.java:156) > [junit4] 2> at >java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java: >1142) > [junit4] 2> at >java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java >:617) > [junit4] 2> at java.lang.Thread.run(Thread.java:745) > [junit4] 2> 1480756 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection x:collection1] >o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration >from collection tmp_collection > [junit4] 2> 1480756 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory > [junit4] 2> 1480756 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at >[/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/te >st/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-00 >1/jetty-002/cores/collection1/], dataDir=[null] > [junit4] 2> 1480756 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to >JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a3bc8e8 > [junit4] 2> 1480757 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.CachingDirectoryFactory return new directory for >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-002/cores/collection1/data > [junit4] 2> 1480757 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore New index directory detected: old=null >new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core >/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670 >-001/jetty-002/cores/collection1/data/index/ > [junit4] 2> 1480757 WARN >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore [collection1] Solr index directory >'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/te >st/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-00 >1/jetty-002/cores/collection1/data/index' doesn't exist. Creating new >index... > [junit4] 2> 1480757 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.CachingDirectoryFactory return new directory for >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-002/cores/collection1/data/index > [junit4] 2> 1480758 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class >org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: >maxMergeAtOnce=46, maxMergeAtOnceExplicit=47, >maxMergedSegmentMB=83.1484375, floorSegmentMB=0.607421875, >forceMergeDeletesPctAllowed=3.2202480380008804, segmentsPerTier=24.0, >maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 > [junit4] 2> 1480758 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 > [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@426c82f8 >lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7b4e8f87),se >gFN=segments_1,generation=1} > [junit4] 2> 1480758 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore newest commit generation = 1 > [junit4] 2> 1480760 INFO >(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore >Looking for old index directories to cleanup for core collection1 in >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-002/cores/collection1/data/ > [junit4] 2> 1480760 WARN >(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.DirectoryFactory >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-002/cores/collection1/data/ does not point to a valid data >directory; skipping clean-up of old index directories. > [junit4] 2> 1480764 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.u.p.UpdateRequestProcessorChain creating >updateRequestProcessorChain "cdcr-processor-chain" > [junit4] 2> 1480764 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, >creating implicit default > [junit4] 2> 1480771 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 1480773 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 1480774 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 1480776 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 1480781 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.RequestHandlers Registered paths: >/admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/se >gments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/ >admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/cdcr >,/update,/admin/file > [junit4] 2> 1480782 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore Using default statsCache cache: >org.apache.solr.search.stats.LocalStatsCache > [junit4] 2> 1480783 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.u.UpdateHandler Using UpdateLog implementation: >org.apache.solr.update.CdcrUpdateLog > [junit4] 2> 1480783 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH >numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 > [junit4] 2> 1480784 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore Hard AutoCommit: disabled > [junit4] 2> 1480784 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore Soft AutoCommit: disabled > [junit4] 2> 1480785 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class >org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: >minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, >maxMergeSizeForForcedMerge=9223372036854775807, >calibrateSizeByDeletes=false, maxMergeDocs=2147483647, >maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] > [junit4] 2> 1480785 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 > [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@426c82f8 >lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7b4e8f87),se >gFN=segments_1,generation=1} > [junit4] 2> 1480786 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SolrCore newest commit generation = 1 > [junit4] 2> 1480786 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.s.SolrIndexSearcher Opening Searcher@6a060f72[collection1] main > [junit4] 2> 1480788 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the >RestManager with znodeBase: /configs/conf1 > [junit4] 2> 1480790 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with >znodeBase: /configs/conf1 > [junit4] 2> 1480790 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.r.RestManager Initializing RestManager with initArgs: {} > [junit4] 2> 1480790 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using >ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 1480792 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.r.ManagedResourceStorage No data found for znode >/configs/conf1/_rest_managed.json > [junit4] 2> 1480793 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json >using ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 1480793 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.r.RestManager Initializing 0 registered ManagedResources > [junit4] 2> 1480793 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.h.ReplicationHandler Commits will be reserved for 10000 > [junit4] 2> 1480800 INFO >(searcherExecutor-4284-thread-1-processing-n:127.0.0.1:58162_ >x:collection1 s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore >[collection1] Registered new searcher Searcher@6a060f72[collection1] >main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1480800 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.u.UpdateLog Looking up max value of version field to seed version >buckets > [junit4] 2> 1480800 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 >version buckets from index > [junit4] 2> 1480800 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.u.VersionInfo No terms found for _version_, cannot seed version >bucket highest value from index > [junit4] 2> 1480801 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.u.UpdateLog Could not find max version in index or recent updates, >using new clock 1507369640428306432 > [junit4] 2> 1480802 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version >1507369640428306432 > [junit4] 2> 1480803 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.ZkController watch zkdir /configs/conf1 > [junit4] 2> 1480804 INFO >(coreLoadExecutor-4283-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.CoreContainer registering core: collection1 > [junit4] 2> 1480805 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.ZkController Register replica - core:collection1 >address:https://127.0.0.1:58162 collection:tmp_collection shard:shard1 > [junit4] 2> 1480815 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.LeaderElector Joined leadership election with path: >/collections/tmp_collection/leader_elect/shard1/election/94210602200137734 >-core_node2-n_0000000000 > [junit4] 2> 1480817 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.ShardLeaderElectionContext Running the leader process for shard >shard1 > [junit4] 2> 1480820 INFO >(zkCallback-1229-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ ] o.a.s.c.DistributedQueue NodeChildrenChanged >fired on path /overseer/queue state SyncConnected > [junit4] 2> 1480821 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. > [junit4] 2> 1480821 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync > [junit4] 2> 1480821 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:58162/collection1/ > [junit4] 2> 1480822 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SyncStrategy Sync Success - now sync replicas to me > [junit4] 2> 1480822 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.SyncStrategy https://127.0.0.1:58162/collection1/ has no replicas > [junit4] 2> 1480822 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.ShardLeaderElectionContext I am the new leader: >https://127.0.0.1:58162/collection1/ shard1 > [junit4] 2> 1480822 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.Overseer processMessage: queueSize: 1, >message = { > [junit4] 2> "operation":"leader", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"tmp_collection"} current state version: 4 > [junit4] 2> 1480827 INFO >(zkCallback-1232-thread-1-processing-n:127.0.0.1:58162_) >[n:127.0.0.1:58162_ ] o.a.s.h.CdcrProcessStateManager Received new >leader state @ tmp_collection:shard1 > [junit4] 2> 1480832 INFO >(zkCallback-1229-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ ] o.a.s.c.DistributedQueue NodeChildrenChanged >fired on path /overseer/queue state SyncConnected > [junit4] 2> 1480834 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.Overseer processMessage: queueSize: 1, >message = { > [junit4] 2> "operation":"leader", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "base_url":"https://127.0.0.1:58162";, > [junit4] 2> "core":"collection1", > [junit4] 2> "state":"active"} current state version: 4 > [junit4] 2> 1480988 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.ZkController We are https://127.0.0.1:58162/collection1/ and >leader is https://127.0.0.1:58162/collection1/ > [junit4] 2> 1480988 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.ZkController No LogReplay needed for core=collection1 >baseURL=https://127.0.0.1:58162 > [junit4] 2> 1480988 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.ZkController I am the leader, no recovery necessary > [junit4] 2> 1480988 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.ZkController publishing state=active > [junit4] 2> 1480988 INFO >(coreZkRegister-4278-thread-1-processing-n:127.0.0.1:58162_ x:collection1 >s:shard1 c:tmp_collection r:core_node2) [n:127.0.0.1:58162_ >c:tmp_collection s:shard1 r:core_node2 x:collection1] >o.a.s.c.ZkController numShards not found on descriptor - reading it from >system property > [junit4] 2> 1480990 INFO >(zkCallback-1229-thread-1-processing-n:127.0.0.1:58155_) >[n:127.0.0.1:58155_ ] o.a.s.c.DistributedQueue NodeChildrenChanged >fired on path /overseer/queue state SyncConnected > [junit4] 2> 1480991 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.Overseer processMessage: queueSize: 1, >message = { > [junit4] 2> "core":"collection1", > [junit4] 2> "core_node_name":"core_node2", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:58162";, > [junit4] 2> "node_name":"127.0.0.1:58162_", > [junit4] 2> "numShards":"2", > [junit4] 2> "state":"active", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} current state version: 5 > [junit4] 2> 1480993 INFO >(OverseerStateUpdate-94210602200137732-127.0.0.1:58155_-n_0000000000) >[n:127.0.0.1:58155_ ] o.a.s.c.o.ReplicaMutator Update state >numShards=2 message={ > [junit4] 2> "core":"collection1", > [junit4] 2> "core_node_name":"core_node2", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:58162";, > [junit4] 2> "node_name":"127.0.0.1:58162_", > [junit4] 2> "numShards":"2", > [junit4] 2> "state":"active", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} > [junit4] 2> 1481445 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.SolrTestCaseJ4 Writing core.properties file to >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-003/cores/collection1 > [junit4] 2> 1481448 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.e.j.s.Server jetty-9.2.11.v20150529 > [junit4] 2> 1481452 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.e.j.s.h.ContextHandler Started >o.e.j.s.ServletContextHandler@14cc4072{/,null,AVAILABLE} > [junit4] 2> 1481455 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.e.j.s.ServerConnector Started >ServerConnector@3d7e821d{SSL-http/1.1}{127.0.0.1:58167} > [junit4] 2> 1481455 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.e.j.s.Server Started @1487091ms > [junit4] 2> 1481455 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {shards=shard3, >hostContext=/, hostPort=58167, >coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/b >uild/solr-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA >6717C1ABDFB670-001/jetty-003/cores} > [junit4] 2> 1481458 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): >sun.misc.Launcher$AppClassLoader@74a14482 > [junit4] 2> 1481458 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: >'/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/te >st/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-00 >1/jetty-003/' > [junit4] 2> 1481491 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.SolrXmlConfig Loading container configuration from >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-003/solr.xml > [junit4] 2> 1481514 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.CoresLocator Config-defined core root directory: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-003/cores > [junit4] 2> 1481514 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.CoreContainer New CoreContainer 1475657514 > [junit4] 2> 1481514 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.CoreContainer Loading cores into CoreContainer >[instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/ >solr-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C >1ABDFB670-001/jetty-003/] > [junit4] 2> 1481515 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.CoreContainer loading shared library: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-003/lib > [junit4] 2> 1481515 WARN >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to >classloader: lib (resolved as: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 >/jetty-003/lib). > [junit4] 2> 1481526 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : >90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : >20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : >2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : >false,useRetries : false, > [junit4] 2> 1481530 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client >with params: socketTimeout=340000&connTimeout=45000&retry=true > [junit4] 2> 1481533 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory > [junit4] 2> 1481533 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.l.LogWatcher Registering Log Listener [Log4j >(org.slf4j.impl.Log4jLoggerFactory)] > [junit4] 2> 1481533 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58151/solr > [junit4] 2> 1481533 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ > ] o.a.s.c.ZkController zkHost includes chroot > [junit4] 2> 1482570 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58167_ ] o.a.s.c.ZkController Register node as live in >ZooKeeper:/live_nodes/127.0.0.1:58167_ > [junit4] 2> 1482579 INFO >(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) >[n:127.0.0.1:58167_ ] o.a.s.c.Overseer Overseer (id=null) closing > [junit4] 2> 1482580 INFO (TEST-CdcrReplication > >[...truncated too long message...] > >et_collection s:shard2 r:core_node4 x:target_collection_shard2_replica1] >o.a.s.c.ZkTestServer connecting to 127.0.0.1:58151 58151 > [junit4] 2> 1924299 INFO (Thread-3290) [ ] o.a.s.c.ZkTestServer >connecting to 127.0.0.1:58151 58151 > [junit4] 2> 1925315 WARN (Thread-3290) [ ] o.a.s.c.ZkTestServer >Watch limit violations: > [junit4] 2> Maximum concurrent create/delete watches above limit: > [junit4] 2> > [junit4] 2> 292 /solr/aliases.json > [junit4] 2> 292 /solr/clusterstate.json > [junit4] 2> 80 /solr/configs/conf1 > [junit4] 2> 37 /solr/collections/source_collection/state.json > [junit4] 2> 35 /solr/collections/target_collection/state.json > [junit4] 2> 3 > /solr/collections/source_collection/leaders/shard1 > [junit4] 2> 2 > /solr/collections/source_collection/leaders/shard2 > [junit4] 2> 2 /solr/collections/tmp_collection/leaders/shard1 > [junit4] 2> 2 /solr/collections/tmp_collection/leaders/shard2 > [junit4] 2> 2 > /solr/collections/target_collection/leaders/shard1 > [junit4] 2> 2 > /solr/collections/target_collection/leaders/shard2 > [junit4] 2> > [junit4] 2> Maximum concurrent data watches above limit: > [junit4] 2> > [junit4] 2> > 45 /solr/collections/source_collection/cdcr/state/process > [junit4] 2> 36 > /solr/collections/source_collection/cdcr/state/buffer > [junit4] 2> 35 > /solr/collections/target_collection/cdcr/state/buffer > [junit4] 2> > 35 /solr/collections/target_collection/cdcr/state/process > [junit4] 2> 8 > /solr/collections/tmp_collection/cdcr/state/buffer > [junit4] 2> 8 > /solr/collections/tmp_collection/cdcr/state/process > [junit4] 2> > 3 > /solr/overseer_elect/election/94210602200137748-127.0.0.1:58273_-n_0000 >000005 > [junit4] 2> > 3 > /solr/overseer_elect/election/94210602200137736-127.0.0.1:58167_-n_0000 >000002 > [junit4] 2> > 3 > /solr/overseer_elect/election/94210602200137732-127.0.0.1:58155_-n_0000 >000000 > [junit4] 2> > 3 > /solr/overseer_elect/election/94210602200137787-127.0.0.1:58295_-n_0000 >000009 > [junit4] 2> > 2 > /solr/collections/source_collection/leader_elect/shard1/election/942106 >02200137774-core_node1-n_0000000000 > [junit4] 2> > 2 > /solr/collections/target_collection/leader_elect/shard2/election/942106 >02200137787-core_node3-n_0000000000 > [junit4] 2> > 2 > /solr/overseer_elect/election/94210602200137752-127.0.0.1:58315_-n_0000 >000007 > [junit4] 2> > 2 > /solr/collections/target_collection/leader_elect/shard1/election/942106 >02200137752-core_node1-n_0000000000 > [junit4] 2> > 2 > /solr/collections/target_collection/leader_elect/shard2/election/942106 >02200137746-core_node2-n_0000000000 > [junit4] 2> > 2 > /solr/collections/source_collection/leader_elect/shard2/election/942106 >02200137736-core_node3-n_0000000000 > [junit4] 2> > 2 > /solr/overseer_elect/election/94210602200137895-127.0.0.1:58257_-n_0000 >000013 > [junit4] 2> > [junit4] 2> Maximum concurrent children watches above limit: > [junit4] 2> > [junit4] 2> 284 /solr/live_nodes > [junit4] 2> 3 /solr/overseer/collection-queue-work > [junit4] 2> 2 /solr/overseer/queue > [junit4] 2> > [junit4] 2> NOTE: reproduce with: ant test >-Dtestcase=CdcrReplicationDistributedZkTest -Dtests.method=doTests >-Dtests.seed=FA6717C1ABDFB670 -Dtests.slow=true -Dtests.locale=ar_SA >-Dtests.timezone=Australia/Sydney -Dtests.asserts=true >-Dtests.file.encoding=UTF-8 > [junit4] FAILURE 450s J1 | CdcrReplicationDistributedZkTest.doTests ><<< > [junit4] > Throwable #1: java.lang.AssertionError: Timeout while >trying to assert update logs @ collection=source_collection > [junit4] > at >__randomizedtesting.SeedInfo.seed([FA6717C1ABDFB670:F20762EDA4D19E7B]:0) > [junit4] > at >org.apache.solr.cloud.CdcrReplicationDistributedZkTest.assertNumberOfTlogF >iles(CdcrReplicationDistributedZkTest.java:644) > [junit4] > at >org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTestUpdateLogSync >hronisation(CdcrReplicationDistributedZkTest.java:384) > [junit4] > at >org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTests(CdcrReplica >tionDistributedZkTest.java:50) > [junit4] > at >org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixed >Statement.callStatement(BaseDistributedSearchTestCase.java:963) > [junit4] > at >org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsState >ment.evaluate(BaseDistributedSearchTestCase.java:938) > [junit4] > at java.lang.Thread.run(Thread.java:745) > [junit4] > Caused by: java.lang.AssertionError: Number of tlogs on >leader: 70 is superior to: 50. > [junit4] > at >org.apache.solr.cloud.CdcrReplicationDistributedZkTest._assertNumberOfTlog >Files(CdcrReplicationDistributedZkTest.java:671) > [junit4] > at >org.apache.solr.cloud.CdcrReplicationDistributedZkTest.assertNumberOfTlogF >iles(CdcrReplicationDistributedZkTest.java:634) > [junit4] > ... 43 more > [junit4] 2> 1925607 INFO >(SUITE-CdcrReplicationDistributedZkTest-seed#[FA6717C1ABDFB670]-worker) >[n:127.0.0.1:58315_ c:target_collection s:shard2 r:core_node4 >x:target_collection_shard2_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore > [junit4] 2> NOTE: leaving temporary files on disk at: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/tes >t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-001 > [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): >{_version_=BlockTreeOrds(blocksize=128), id=Lucene50(blocksize=128)}, >docValues:{}, sim=DefaultSimilarity, locale=ar_SA, >timezone=Australia/Sydney > [junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_45 >(64-bit)/cpus=3,threads=1,free=82748480,total=438304768 > [junit4] 2> NOTE: All tests run in this JVM: >[TestOverriddenPrefixQueryForCustomFieldType, >DistributedFacetPivotWhiteBoxTest, TestExceedMaxTermLength, >DirectUpdateHandlerOptimizeTest, UpdateParamsTest, TestSolrDynamicMBean, >SimpleMLTQParserTest, AutoCommitTest, TestReqParamsAPI, >CoreMergeIndexesAdminHandlerTest, CollectionsAPIDistributedZkTest, >BasicZkTest, TestJoin, TestManagedSchema, SolrIndexConfigTest, >TestCoreDiscovery, DistributedTermsComponentTest, TestMissingGroups, >TestInitParams, BigEndianAscendingWordSerializerTest, >CleanupOldIndexTest, TestSchemaManager, SmileWriterTest, >TestSolr4Spatial, SuggestComponentTest, SolrCmdDistributorTest, >CdcrRequestHandlerTest, TestCollapseQParserPlugin, AnalyticsQueryTest, >HighlighterConfigTest, ZkNodePropsTest, TestLRUStatsCache, >TestSimpleQParserPlugin, TestSchemaNameResource, HdfsLockFactoryTest, >ChaosMonkeySafeLeaderTest, ExitableDirectoryReaderTest, >TestSolrCoreProperties, DistribCursorPagingTest, >DistributedFacetPivotLongTailTest, CurrencyFieldOpenExchangeTest, >ParsingFieldUpdateProcessorsTest, HdfsBasicDistributedZk2Test, >SignatureUpdateProcessorFactoryTest, TestCopyFieldCollectionResource, >IndexSchemaTest, TestHighlightDedupGrouping, TestValueSourceCache, >BinaryUpdateRequestHandlerTest, SpellCheckComponentTest, >DebugComponentTest, HLLUtilTest, CdcrReplicationHandlerTest, >ResponseHeaderTest, TestDistributedStatsComponentCardinality, >TestConfigReload, PolyFieldTest, CollectionStateFormat2Test, >SimplePostToolTest, TestSmileRequest, WordBreakSolrSpellCheckerTest, >TermVectorComponentTest, LeaderElectionIntegrationTest, >StatelessScriptUpdateProcessorFactoryTest, HdfsUnloadDistributedZkTest, >TestBlendedInfixSuggestions, TestSolr4Spatial2, ReturnFieldsTest, >PrimitiveFieldTypeTest, DocValuesMissingTest, AddBlockUpdateTest, >CdcrVersionReplicationTest, TestDistributedGrouping, >TestClusterStateMutator, TestDefaultSimilarityFactory, FileUtilsTest, >TestSolrDeletionPolicy1, TestSearchPerf, RequestLoggingTest, >TestQuerySenderListener, RankQueryTest, ShardRoutingCustomTest, >MBeansHandlerTest, CSVRequestHandlerTest, >TestPHPSerializedResponseWriter, SpatialFilterTest, >DisMaxRequestHandlerTest, TestMiniSolrCloudCluster, >DistributedQueryComponentOptimizationTest, DirectSolrSpellCheckerTest, >TestFieldSortValues, DistributedMLTComponentTest, >SpellingQueryConverterTest, HdfsThreadLeakTest, TestInfoStreamLogging, >BadIndexSchemaTest, CollectionTooManyReplicasTest, >OverseerCollectionProcessorTest, QueryEqualityTest, >SpatialRPTFieldTypeTest, TestRTGBase, TestConfigSetImmutable, >AliasIntegrationTest, DateMathParserTest, >UpdateRequestProcessorFactoryTest, TestExactStatsCache, >AddSchemaFieldsUpdateProcessorFactoryTest, TestFoldingMultitermQuery, >SolrTestCaseJ4Test, DistributedFacetPivotSmallAdvancedTest, >TestFastWriter, SolrCloudExampleTest, TestQuerySenderNoQuery, >TestRangeQuery, TestNamedUpdateProcessors, >CdcrReplicationDistributedZkTest] > [junit4] Completed [253/525] on J1 in 449.82s, 1 test, 1 failure <<< >FAILURES! > >[...truncated 831 lines...] >BUILD FAILED >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:526: The >following error occurred while executing this line: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:474: The >following error occurred while executing this line: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The >following error occurred while executing this line: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: >The following error occurred while executing this line: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:230: The >following error occurred while executing this line: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:51 >3: The following error occurred while executing this line: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml: >1426: The following error occurred while executing this line: >/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml: >981: There were test failures: 525 suites, 2071 tests, 1 failure, 65 >ignored (25 assumptions) > >Total time: 77 minutes 47 seconds >Build step 'Invoke Ant' marked build as failure >Archiving artifacts >Recording test results >Email was triggered for: Failure - Any >Sending email for trigger: Failure - Any > > --------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
