Hi Erick, Thanks so much for your explanation. I just run all the tests again and success. It seems that the CDCR tests are not always failing. I¹ll take your suggestions and just ignore them for now. Thanks!
Best, Zack On 7/22/15, 9:36 PM, "Erick Erickson" <[email protected]> wrote: >Zack: > >Ignore it for the time being. We're working on it, it's a complex bit >of code. Unfortunately I can't make it fail locally so we have have to >use Jenkins and its many environments to try to figure out what the >magic is. > >NOTE: this code is _not_ in the 5x code line, I won't put it there >unless and until we get stable tests out of it. I suspect (but don't >quite know) that this something "interesting" in the test framework. >It's always fun to try to figure out whether it's a test artifact and >how to work around it or whether it's really a problem with the code >somewhere. > >If you want to, you can just add the @Ignore annotation to any of the >CDCR tests locally, but do _not_ include that change with the patch. >There's really no need to though, the end of the test run prints out >all the failing tests and if any CDCR* tests are labeled you may >safely ignore them. > >Best, >Erick > >On Wed, Jul 22, 2015 at 9:22 PM, Liang, Hsuan Tzu <[email protected]> wrote: >> 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 th 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.doTe >>> >>>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_collectin >>> at >>>__randomizedsting.SeedInfo.seed([FA6717C1ABDFB670:F20762EDA4D19E7B]:0) >>> at >>>>org.apache.solr.cloud.CdcrReplicationDistributedZkTest.assertNumberOfTl >>>>ogF>iles(CdcrReplicationDistributedZkTest.java:644) >>> at >>>orgapache.solr.cloud.CdcrReplicationDistributedZkTest.doTestUpdateLogSyn >>>c >>>hronisation(CdcrReplicationDistributedZkTest.java:384) >>> at >>>org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTests(CdcrRepli >>>ca >>>tionDistributedZkTest.va:50) >>> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native ethod) >>> at >>>sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccesorImpl.java >>>: >>>62) >>> at >>>sun.reflect.DelegatingMethodAccessorImpl.invoke(DlegatingMethodAccessorI >>>m >>>pl.java:43) >>> at java.lang.reflect.Method.invoke(Method.java:497) >> at >>>com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(andomizedRunn >>>e >>>r.java:1627) >>> at >>>com.carrotsearch.randomizedtesting.RandmizedRunner$6.evaluate(Randomized >>>R >>>unner.java:836) >>> at >> >>com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Randomized >>R >>>unner.java:872) >>> at >>>com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Randomize >>>dR >>>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.e >>>va >>>luate(SystemPropertiesRestoreRule.java:57) >>> at >>>org.apache.lucene.util.TestRuleSetupTearwnChained$1.evaluate(TestRuleSet >>>upTeardownChained.java:50) >>> a >>>org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBefore >>>f >>>terRule.java:46) >>> at >>>org.apache.lucene.util.TesRuleThreadAndTestName$1.evaluate(TestRuleThrea >>>d >>>AndTestName.java:49) >>> at >>>org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRul >>>eI >>>gnoreAfterMaxFailures.java:65) >>> at >>>org.apache.lucene.util.TestRuleMrkFailure$1.evaluate(TestRuleMarkFailure >>>.java:48) >>> at >>>com.carrotsearch.randomizedtesting.rules.StatemetAdapter.evaluate(Statem >>>e >>>ntAdapter.java:36) >>> at >>>com.carrotsearch.randoizedtesting.ThreadLeakControl$StatementRunner.run( >>>T >>>hreadeakontrol.java:365) >>> at >>>com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask( >>>Th >>>readLeakControl.java:798) >>> at >>>com.carrotsearch.randomizedtesting.ThreadLeakControl$3.valuate(ThreadLe >>>ak >>>Control.jav458) >>> at >>>com.carrotsearch.randomizedtesting.RandomizedRunner.unSingleTest(Randomi >>>z >>>edRunner.java:845) >>> at >>>com.carrotsearch.randomizetesting.RandomizedRunner$3.evaluate(Randoized >>>R >>>unner.java:47) >>> at >>>com.carrotsearch.randomizedtesting.RandomizedRunner$4evaluate(Randomized >>>R >>>unner.java:781) >>> at >>>co.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(andomizedR >>>unner.java:792) >>> at >>>com.carrotsearch.randomizedtesting.rles.StatementAdapter.evaluate(Statem >>>e >>>ntAdapter.java:36) >>> at >>>com.carrotsearch.randomidtesting.rules.SystemPropertiesRestoreRule$1.eva >>luate(SystemPrpertiesRestoreRule.java:57) >>> at >>>org.apache.lucene.util.AbstractBeforeAftrRule$1.evaluate(AbstractBeforeA >>>f >>>terRule.java:46) >>> at >>>com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(State >>>me >>>tAdapter.java:36) >>> at >>>org.apache.lucene.util.TestRueStoreClassName$1.evaluate(TestRuleStoreCl >>>as >>>sName.java:42) >>> at >>>com.carrotsear.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRu >>>le$1evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) >>> at >>>com.carrotsearh.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsR >>>u >>>le$1.evaluate(NoSdowingOrOverridesOnMethodsRule.java:39) >>> at >>>com.carrotsearch.randomizetesting.rules.StatementAdaper.evaluate(Statem >>>e >>>ntAdapter.java:36) >>> at >>>com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Satem >>>e >>>ntAdapter.java:36) >>> at >>>com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(State >>>me >>>ntAdapter.java:36) >>> at >>>org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAss >>>er >>>tionsReqred.java:54) >>> at >>>org.apache.lucene.util.TestRuleMarkFilure$1.evaluateTestRuleMarkFailure >>>. >>>java:48) >>> at >>>org.apache.luceneutil.TestRuleIgnoreAfterMaFailures$1.evaluate(TestRuleI >>>gnoreAfterMaxFailures.java:65) >>> at >>>org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnor >>>eT >>>estSuites.java:55) >>> at >>>com.carrotsearch.randomizedtesting.rules.StatemetAdapter.evaluate(Statem >>>e >>>ntAdapter.java:36) >>> at >>>com.carrotsearch.randomizedtestinghreadLeakControl$StatemetRunner.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._assertNumberOfTlo >>>g >>>Files(CdcrReplicationDistributedZkTest.jaa:671) >>> t >>>org.apache.solr.cloud.CdcrReplicationDistributedZkTest.ssertNumberOfTlog >>>F >>>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-00 >>>1 >>>/init-core-data-001 >>> [jnit4] 2> 1475810 INFO >>>(SUITECdcrReplicationDistributedZkTest-seed#[FA6717C1AFB670]-worker) [ >>> ] o.a.s.SolrTestCaseJ4 Randomiz(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-file >>>s >>>/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-file >>>s >>>/solr/collectio1/conf/schema15.xml to /configs/conf1/schema.xml >>> [jnit4] 2> 1475954 INFO >>>(TEST-CdcrReplicationDistributedZkTest.doTes-seed#[FA6717C1ABDFB670]) [ >>> ] o.a.s.c.AbstractZkTestCas >>>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-CdcrReplicionDistributedZkest.doTests-seed#[FA6717C1ABDFB670]) [ >>> ] o..s.c.AbstractZkTestCase put >>>/Users/jenkins/workspace/Lucene-Solr-trunk-MacSX/solr/core/src/test-file >>>s >>>/solr/collection1/conf/stopwords.txt to /confi/conf1/stopwords.txt >>> [junit4] 2> 1475962 INFO >>>(TEST-CdcrRplictionDistributedZkTest.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/prtwords.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-fle >>>s >>>/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 >>>/Usrs/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-fil >>>es >>>/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/maping-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/ore/src/test-file >>>s >>>/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/workspaceLucene-Sor-trunk-MacOSX/solr/core/src/test-file >>>s >>>/solr/collection/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-CdcrRepliationDistributedZkTest.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.CdcrReplcationDistributedZkTest_FA6717C1ABDFB670-00 >>>1 >>>/jety-001/cores/collection1 >>> [junit4] 2> 1476354 INFO >>>(TEST-CdrReplicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ >>> ] e.j.s.Server jetty-9.2.11.v2150529 >>> [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 Sarted @1482014ms >>> [junit4] 2> 1476376 INFO >>>(TESTCdcrReplicationDistributedZkTest.doTests-s6717C1ABDFB670]) [ >>> ] 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_F >>>A >>>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 INF >>>(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.CdrReplicaionDistributedZkTest_FA6717C1ABDFB670-0 >>>0 >>>1/jetty-001/' >>> [junit4] 2> 16419 INFO >>>(TEST-CdcrReplicationDistributedZkTest.doTests-seed#[FA6717C1BDFB670]) [ >>> ] o.a.s.c.SolrXmlConfig ading container configuration from >>>/Users/jenkins/workspace/Lucne-Solr-trunk-MacOSX/solr/build/olr-core/te >>>s >>>t/J1/temp/solr.cloud.CdcrRepicationDistributedZkTest_FA6717C1ABDFB670-00 >>>1 >>>/jetty-001/solr.xml >>> [junit4] 2> 1476435 INFO >>>(TEST-CdcrReplicationDistributZkTest.doTests-seed#[FA6717C1ABDFB670]) [ >>> ] o.a.s.c.CoresLoctor Config-defined core rot directory: >>>/Users/jenkins/workspace/Lucene-Sor-trunk-MacOSX/solr/build/solr-core/te >>>s >>>t/J1/temp/solr.cloud.CdcrRepliationDistributedZkTest_FA6717C1ABDFB670-00 >>>1 >>>/jetty-001/cores >>> [junit4] 2> 1476435 INFO >>>(TEST-CdcrRepliationDistributedZkTet.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_FA6717 >>>C >>>1ABDFB670-001/jetty-00/] >>> [junit4] 2> 1476435 INFO >>>(TEST-CdcrReplicaonDistributedZkTest.doTests-seed#[F6717C1ABDFB670]) [ >>> ] o.as.c.CoreContainer loading shared library: >>>/Users/jenkins/workspace/Lucene-olr-trunk-MacOSX/solr/build/solr-core/te >>>s >>>t/J1/temp/solr.cloud.CdcrReplicationDtributedZkTest_FA6717C1ABDFB670-001 >>>/jetty-001/lib >>> [junit4] 2> 1476436 WARN >>>(TEST-CdcrReplicatonDistributedZkTest.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/te >>>s >>t/J1/temp/solr.cloud.CdrReplicationDistributedZkTest_FA6717C1ABDFB670-00 >>>1 >>>/jety-001/lib). >>> [junit4] 2> 1476451 INFO >>>(TEST-CdcrRepicationDistributedZkTest.doTests-seed#[FA6717C1ABDFB670]) [ >>> ] o.a.s.h.c.HttpShardHandleFactory 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.UpdaeShardHandler 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 isorg.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 >>>(TES-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 choot >>> [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-CdcrReplicationDistributedZkTst.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 leadershp 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#[F6717C1ABDFB670]) >>>[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 >>>autoReplicFailoverWorkLoopDelay=10000 >>>autoReplicaailoverWaitAfterExpiration=30000 >>>autoReplicaFailoverBaNodeExpiration=60000 >>> [junit4] 2> 1476552 INFO >>>(OveseerCollectionProcessor-94210602200137732-127.0.0.1:58155_-n_0000000 >>>0 >>>00) [127.0.0.1:58155_ ] o.a.s.c.OverseerCollectionProcessor Process >>>currnt queue of collection creations >>> [junit4] 2> 1476553 INFO >>>(OverseeStateUpdate-9410602200137732-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 mdule. >>> [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/te >>>s >>>t/J1/temp/solr.clou.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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-ore/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA677C1 >>>ABDFB670-001/jetty-001/cores/collection1, collection=tmp_collecton, >>>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-CdcrRepliationDistributedZkTest.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/te >>s >>>t/J1/temp/solr.cloud.CdcrReplictionDistributedZkTest_FA6717C1ABDFB670-00 >>>1 >>>/jetty-001/cores/collction1/ >>> [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/sol >>>r >>>-core/test/J1 >>> [jnit4] 2> 1476571 INFO >>>(TEST-CdcrReplicationDistributdZkTest.doTests-seed#[FA6717C1ABDFB670]) >>>[n:127.0.0.1:58155_ ] o.a.s.sSolrDspatchFilter >>>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.158155_ ] 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", >>> [juni4] 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 messge={ >>> [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> "hard":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:ollection1] 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.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 >>>(coreLoadxecutor-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 INO >>>(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 >>>[collectin1] 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 keyfield: 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 raes 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-cor >>>e >>>/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(ConfigSet >>>S >>>ervice.jav:114) >>> [junit4] 2>at >>>org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java76) >>> [junit4] 2> at >>>org.apache.solr.core.CoreContainer.ceate(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.utilExecutorUtil$MDCAwareThreadPoolExecutor$1.ru >>>n( >>>Executortil.java:156) >>> [junit4] 2> at >>>java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.jav >>>a: >>>1142) >>> [junit4] 2> at >>>java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.ja >>>va >>>:617) >>> [junit4] 2> a java.ang.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:17.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-Sol-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-: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/t >>>es >>>t/J1/temp/solr.clou.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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-co >>>re >>>/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB6 >>>70 >>>-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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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),s >>>e >>>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-0 >>>01 >>>/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-00 >>>1 >>>/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,/cd >>>cr >>>,/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/942106022001377 >>>32 >>>-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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/buil >>>d/ >>>solr-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA671 >>>7C >>>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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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_FA6717 >>>C1 >>>ABDFB670-001/jetty-002/cores/collection1, collection=tmp_collection, >>>absoluteInstDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/b >>>ui >>>ld/solr-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA >>>67 >>>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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/so >>>lr >>>-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-co >>>re >>>/test/J1 >>> [junit4] 2> at >>>org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLo >>>ad >>>er.java:99) >>> [junit4] 2> at >>>org.apache.solr.core.ConfigSetProperties.readFromResourceLoader(ConfigSe >>>tP >>>roperties.java:49) >>> [junit4] 2> at >>>org.apache.solr.core.ConfigSetService.createConfigSetProperties(ConfigSe >>>tS >>>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.ru >>>n( >>>ExecutorUtil.java:156) >>> [junit4] 2> at >>>java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.jav >>>a: >>>1142) >>> [junit4] 2> at >>>java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.ja >>>va >>>: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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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-co >>>re >>>/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB6 >>>70 >>>-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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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,/cd >>>cr >>>,/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/942106022001377 >>>34 >>>-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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/buil >>>d/ >>>solr-core/test/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA671 >>>7C >>>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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>>/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.assertNumberOfTlo >>>gF >>>iles(CdcrReplicationDistributedZkTest.java:644) >>> [junit4] > at >>>org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTestUpdateLogSy >>>nc >>>hronisation(CdcrReplicationDistributedZkTest.java:384) >>> [junit4] > at >>>org.apache.solr.cloud.CdcrReplicationDistributedZkTest.doTests(CdcrRepli >>>ca >>>tionDistributedZkTest.java:50) >>> [junit4] > at >>>org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFix >>>ed >>>Statement.callStatement(BaseDistributedSearchTestCase.java:963) >>> [junit4] > at >>>org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsSta >>>te >>>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._assertNumberOfTl >>>og >>>Files(CdcrReplicationDistributedZkTest.java:671) >>> [junit4] > at >>>org.apache.solr.cloud.CdcrReplicationDistributedZkTest.assertNumberOfTlo >>>gF >>>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/t >>>es >>>t/J1/temp/solr.cloud.CdcrReplicationDistributedZkTest_FA6717C1ABDFB670-0 >>>01 >>> [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.xm >>>l: >>>1426: The following error occurred while executing this line: >>>/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xm >>>l: >>>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] >> > >--------------------------------------------------------------------- >To unsubscribe, e-mail: [email protected] >For additional commands, e-mail: [email protected] > --------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
