This seems to be a problem @ the ASF. I checked the mail server, the mail was only delivered *once* to ASF. I we arrive those mail any longer I will contact infra!
serv1:~# grep '730516776.47.1439413674893.JavaMail.jenkins@serv1' /var/log/exim4/mainlog.1 | wc -l 1 >From log: 2015-08-12 21:07:54 1ZPdFa-0006Ix-Ss <= [email protected] H=localhost.localdomain (serv1) [127.0.0.1] P=esmtp S=103656 id=730516776.47.1439413674893.JavaMail.jenkins@serv1 2015-08-12 21:08:03 1ZPdFa-0006Ix-Ss => [email protected] R=dnslookup T=remote_smtp H=mx1-us-west.apache.org [54.191.145.13] X=TLS1.2:DHE_RSA_AES_128_CBC_SHA1:128 DN="C=US,ST=Maryland,L=Forest Hill,O=The Apache Software Foundation,OU=Infrastructure,CN=*.apache.org" C="250 2.0.0 Ok: queued as 776CA32C07" 2015-08-12 21:08:03 1ZPdFa-0006Ix-Ss Completed >From the mail headers, it looks like this mail loops around, just count the >"Received: " headers! Uwe ----- Uwe Schindler H.-H.-Meier-Allee 63, D-28213 Bremen http://www.thetaphi.de eMail: [email protected] > -----Original Message----- > From: Policeman Jenkins Server [mailto:[email protected]] > Sent: Wednesday, August 12, 2015 11:08 PM > To: [email protected] > Subject: [JENKINS-EA] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0_60-ea-b24) - > Build # 13833 - Failure! > Importance: Low > > Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/13833/ > Java: 32bit/jdk1.8.0_60-ea-b24 -server -XX:+UseParallelGC > > 1 tests failed. > FAILED: org.apache.solr.cloud.CdcrReplicationHandlerTest.doTest > > Error Message: > There are still nodes recoverying - waited for 330 seconds > > Stack Trace: > java.lang.AssertionError: There are still nodes recoverying - waited for 330 > seconds > at > __randomizedtesting.SeedInfo.seed([C35243D1951C71D7:6416FB75F8A7626 > E]:0) > at org.junit.Assert.fail(Assert.java:93) > at > org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish( > AbstractDistribZkTestBase.java:172) > at > org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish( > AbstractDistribZkTestBase.java:133) > at > org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish( > AbstractDistribZkTestBase.java:128) > at > org.apache.solr.cloud.BaseCdcrDistributedZkTest.waitForRecoveriesToFinish > (BaseCdcrDistributedZkTest.java:465) > at > org.apache.solr.cloud.BaseCdcrDistributedZkTest.clearSourceCollection(Base > CdcrDistributedZkTest.java:319) > at > org.apache.solr.cloud.CdcrReplicationHandlerTest.doTestPartialReplicationWi > thTruncatedTlog(CdcrReplicationHandlerTest.java:121) > at > org.apache.solr.cloud.CdcrReplicationHandlerTest.doTest(CdcrReplicationHa > ndlerTest.java:52) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.j > ava:62) > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces > sorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:497) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomize > dRunner.java:1627) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Rando > mizedRunner.java:836) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Rando > mizedRunner.java:872) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Rando > mizedRunner.java:886) > at > org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFi > xedStatement.callStatement(BaseDistributedSearchTestCase.java:963) > at > org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsSt > atement.evaluate(BaseDistributedSearchTestCase.java:938) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1. > evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRule > SetupTeardownChained.java:50) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeA > fterRule.java:46) > at > org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleTh > readAndTestName.java:49) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRule > IgnoreAfterMaxFailures.java:65) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure > .java:48) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat > ementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner. > run(ThreadLeakControl.java:365) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask > (ThreadLeakControl.java:798) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadL > eakControl.java:458) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran > domizedRunner.java:845) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Rando > mizedRunner.java:747) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Rando > mizedRunner.java:781) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Rando > mizedRunner.java:792) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat > ementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1. > evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeA > fterRule.java:46) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat > ementAdapter.java:36) > at > org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreCl > assName.java:42) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMet > hodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMet > hodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat > ementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat > ementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat > ementAdapter.java:36) > at > org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAss > ertionsRequired.java:54) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure > .java:48) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRule > IgnoreAfterMaxFailures.java:65) > at > org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnore > TestSuites.java:55) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(Stat > ementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner. > run(ThreadLeakControl.java:365) > at java.lang.Thread.run(Thread.java:745) > > > > > Build Log: > [...truncated 11079 lines...] > [junit4] Suite: org.apache.solr.cloud.CdcrReplicationHandlerTest > [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr- > trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/init-core-data-001 > [junit4] 2> 1114461 INFO (SUITE-CdcrReplicationHandlerTest- > seed#[C35243D1951C71D7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl > (false) and clientAuth (false) > [junit4] 2> 1114461 INFO (SUITE-CdcrReplicationHandlerTest- > seed#[C35243D1951C71D7]-worker) [ ] > o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / > [junit4] 2> 1114462 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST > SERVER > [junit4] 2> 1114463 INFO (Thread-2638) [ ] o.a.s.c.ZkTestServer > client > port:0.0.0.0/0.0.0.0:0 > [junit4] 2> 1114463 INFO (Thread-2638) [ ] o.a.s.c.ZkTestServer > Starting > server > [junit4] 2> 1114563 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.ZkTestServer start zk server on > port:53819 > [junit4] 2> 1114563 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkCredentialsProvider > [junit4] 2> 1114563 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Waiting for > client to connect to ZooKeeper > [junit4] 2> 1114565 INFO (zkCallback-789-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@1289dff > name:ZooKeeperConnection Watcher:127.0.0.1:53819 got event > WatchedEvent state:SyncConnected type:None path:null path:null > type:None > [junit4] 2> 1114566 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Client is > connected to ZooKeeper > [junit4] 2> 1114566 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkACLProvider > [junit4] 2> 1114566 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr > [junit4] 2> 1114568 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkCredentialsProvider > [junit4] 2> 1114568 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Waiting for > client to connect to ZooKeeper > [junit4] 2> 1114569 INFO (zkCallback-790-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@1d9a94b > name:ZooKeeperConnection Watcher:127.0.0.1:53819/solr got event > WatchedEvent state:SyncConnected type:None path:null path:null > type:None > [junit4] 2> 1114569 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Client is > connected to ZooKeeper > [junit4] 2> 1114569 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkACLProvider > [junit4] 2> 1114570 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /collections/collection1 > [junit4] 2> 1114570 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /collections/collection1/shards > [junit4] 2> 1114571 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /collections/control_collection > [junit4] 2> 1114572 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /collections/control_collection/shards > [junit4] 2> 1114572 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test- > files/solr/collection1/conf/solrconfig-cdcr.xml to > /configs/conf1/solrconfig.xml > [junit4] 2> 1114573 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/solrconfig.xml > [junit4] 2> 1114574 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test- > files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml > [junit4] 2> 1114574 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/schema.xml > [junit4] 2> 1114575 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test- > files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to > /configs/conf1/solrconfig.snippet.randomindexconfig.xml > [junit4] 2> 1114576 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/solrconfig.snippet.randomindexconfig.xml > [junit4] 2> 1114576 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test- > files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt > [junit4] 2> 1114577 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/stopwords.txt > [junit4] 2> 1114577 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test- > files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt > [junit4] 2> 1114578 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/protwords.txt > [junit4] 2> 1114578 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test- > files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml > [junit4] 2> 1114579 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/currency.xml > [junit4] 2> 1114579 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test- > files/solr/collection1/conf/enumsConfig.xml to > /configs/conf1/enumsConfig.xml > [junit4] 2> 1114580 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/enumsConfig.xml > [junit4] 2> 1114580 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test- > files/solr/collection1/conf/open-exchange-rates.json to > /configs/conf1/open-exchange-rates.json > [junit4] 2> 1114580 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/open-exchange-rates.json > [junit4] 2> 1114581 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test- > files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to > /configs/conf1/mapping-ISOLatin1Accent.txt > [junit4] 2> 1114581 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/mapping-ISOLatin1Accent.txt > [junit4] 2> 1114582 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test- > files/solr/collection1/conf/old_synonyms.txt to > /configs/conf1/old_synonyms.txt > [junit4] 2> 1114582 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/old_synonyms.txt > [junit4] 2> 1114583 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test- > files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt > [junit4] 2> 1114583 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/synonyms.txt > [junit4] 2> 1114585 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTest > [junit4] 2> 1114661 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties > file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores/collection1 > [junit4] 2> 1114663 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 > [junit4] 2> 1114664 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@11528cf{/,null,AVAILABLE} > [junit4] 2> 1114665 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.e.j.s.ServerConnector Started > ServerConnector@80bc11{HTTP/1.1}{127.0.0.1:50099} > [junit4] 2> 1114665 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.e.j.s.Server Started @1116653ms > [junit4] 2> 1114666 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: > {shards=shard1, hostContext=/, hostPort=50099, > coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores} > [junit4] 2> 1114666 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.s.SolrDispatchFilter > SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@e2f2a > [junit4] 2> 1114666 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.SolrResourceLoader new > SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr- > trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/' > [junit4] 2> 1114681 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkCredentialsProvider > [junit4] 2> 1114682 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Waiting for > client to connect to ZooKeeper > [junit4] 2> 1114682 INFO (zkCallback-791-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@18e7d7e > name:ZooKeeperConnection Watcher:127.0.0.1:53819/solr got event > WatchedEvent state:SyncConnected type:None path:null path:null > type:None > [junit4] 2> 1114683 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Client is > connected to ZooKeeper > [junit4] 2> 1114683 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkACLProvider > [junit4] 2> 1114684 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml > from SolrHome (not found in ZooKeeper) > [junit4] 2> 1114684 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.SolrXmlConfig Loading container > configuration from /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/solr.xml > [junit4] 2> 1114689 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.CoresLocator Config-defined core > root directory: /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores > [junit4] 2> 1114689 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.CoreContainer New CoreContainer > 24391898 > [junit4] 2> 1114689 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.CoreContainer Loading cores into > CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/] > [junit4] 2> 1114689 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.CoreContainer loading shared library: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/lib > [junit4] 2> 1114690 WARN (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.SolrResourceLoader Can't find (or > read) directory to add to classloader: lib (resolved as: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/lib). > [junit4] 2> 1114697 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] 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> 1114698 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.u.UpdateShardHandler Creating > UpdateShardHandler HTTP client with params: > socketTimeout=340000&connTimeout=45000&retry=true > [junit4] 2> 1114699 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.l.LogWatcher SLF4J impl is > org.slf4j.impl.Log4jLoggerFactory > [junit4] 2> 1114699 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.l.LogWatcher Registering Log Listener > [Log4j (org.slf4j.impl.Log4jLoggerFactory)] > [junit4] 2> 1114699 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.ZkContainer Zookeeper > client=127.0.0.1:53819/solr > [junit4] 2> 1114699 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.ZkController zkHost includes chroot > [junit4] 2> 1114700 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkCredentialsProvider > [junit4] 2> 1114700 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Waiting for > client to connect to ZooKeeper > [junit4] 2> 1114701 INFO (zkCallback-793-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@1b85bf0 > name:ZooKeeperConnection Watcher:127.0.0.1:53819 got event > WatchedEvent state:SyncConnected type:None path:null path:null > type:None > [junit4] 2> 1114701 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Client is > connected to ZooKeeper > [junit4] 2> 1114702 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkACLProvider > [junit4] 2> 1114703 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] > o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 1114704 INFO (zkCallback-794-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.c.c.ConnectionManager > Watcher org.apache.solr.common.cloud.ConnectionManager@1f45f9c > name:ZooKeeperConnection Watcher:127.0.0.1:53819/solr got event > WatchedEvent state:SyncConnected type:None path:null path:null > type:None > [junit4] 2> 1114704 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] > o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 1114705 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /overseer/queue > [junit4] 2> 1114706 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /overseer/collection-queue-work > [junit4] 2> 1114707 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /overseer/collection-map-running > [junit4] 2> 1114708 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /overseer/collection-map-completed > [junit4] 2> 1114709 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /overseer/collection-map-failure > [junit4] 2> 1114710 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /live_nodes > [junit4] 2> 1114710 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /aliases.json > [junit4] 2> 1114711 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /clusterstate.json > [junit4] 2> 1114711 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /security.json > [junit4] 2> 1114712 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.ZkController > Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50099_ > [junit4] 2> 1114712 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /live_nodes/127.0.0.1:50099_ > [junit4] 2> 1114713 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /overseer_elect > [junit4] 2> 1114713 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /overseer_elect/election > [junit4] 2> 1114715 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.Overseer > Overseer (id=null) closing > [junit4] 2> 1114715 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.LeaderElector > Joined leadership election with path: > /overseer_elect/election/94333370514341892-127.0.0.1:50099_- > n_0000000000 > [junit4] 2> 1114716 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] > o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50099_ > [junit4] 2> 1114716 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /overseer_elect/leader > [junit4] 2> 1114717 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.Overseer > Overseer (id=94333370514341892-127.0.0.1:50099_-n_0000000000) starting > [junit4] 2> 1114717 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.SolrZkClient > makePath: /overseer/queue-work > [junit4] 2> 1114720 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] > o.a.s.c.OverseerAutoReplicaFailoverThread Starting > OverseerAutoReplicaFailoverThread > autoReplicaFailoverWorkLoopDelay=10000 > autoReplicaFailoverWaitAfterExpiration=30000 > autoReplicaFailoverBadNodeExpiration=60000 > [junit4] 2> 1114720 INFO (OverseerCollectionProcessor- > 94333370514341892-127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] > o.a.s.c.OverseerProcessor Process current queue of overseer operations > [junit4] 2> 1114720 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.c.ZkStateReader > Updating cluster state from ZooKeeper... > [junit4] 2> 1114721 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] o.a.s.c.Overseer > Starting to work on the main queue > [junit4] 2> 1114746 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.CoreContainer > Security conf doesn't exist. Skipping setup for authorization module. > [junit4] 2> 1114746 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.CoreContainer No > authentication plugin used. > [junit4] 2> 1114747 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.CoresLocator > Looking for core definitions underneath /home/jenkins/workspace/Lucene- > Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores > [junit4] 2> 1114748 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.SolrCore Created > CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, > schema=schema.xml, loadOnStartup=true, > configSetProperties=configsetprops.json, > instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores/collection1, collection=tmp_collection, > absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} > [junit4] 2> 1114748 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.CoresLocator > Found core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores/collection1/ > [junit4] 2> 1114748 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.c.CoresLocator > Found 1 core definitions > [junit4] 2> 1114749 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.s.SolrDispatchFilter > user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1 > [junit4] 2> 1114749 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:50099_ ] o.a.s.s.SolrDispatchFilter > SolrDispatchFilter.init() done > [junit4] 2> 1114752 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.ZkController publishing state=down > [junit4] 2> 1114753 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.ZkController numShards not found on descriptor - reading it from > system property > [junit4] 2> 1114753 INFO (zkCallback-794-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.c.DistributedQueue > NodeChildrenChanged fired on path /overseer/queue state SyncConnected > [junit4] 2> 1114753 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.ZkController look for our core node name > [junit4] 2> 1114753 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] o.a.s.c.Overseer > processMessage: queueSize: 1, message = { > [junit4] 2> "core":"collection1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"http://127.0.0.1:50099";, > [junit4] 2> "node_name":"127.0.0.1:50099_", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} current state version: 0 > [junit4] 2> 1114754 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] > o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ > [junit4] 2> "core":"collection1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"http://127.0.0.1:50099";, > [junit4] 2> "node_name":"127.0.0.1:50099_", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} > [junit4] 2> 1114754 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] > o.a.s.c.o.ClusterStateMutator building a new cName: tmp_collection > [junit4] 2> 1114754 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] > o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 > [junit4] 2> 1114755 INFO (zkCallback-794-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.c.c.ZkStateReader A cluster > state change: WatchedEvent state:SyncConnected type:NodeDataChanged > path:/clusterstate.json, has occurred - updating... (live nodes size: 1) > [junit4] 2> 1115753 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 > [junit4] 2> 1115753 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.ZkController Check for collection zkNode:tmp_collection > [junit4] 2> 1115754 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.ZkController Creating collection in ZooKeeper:tmp_collection > [junit4] 2> 1115754 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.ZkController Looking for collection configName > [junit4] 2> 1115754 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.ZkController Only one config set found in zk - using it:conf1 > [junit4] 2> 1115754 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.c.SolrZkClient makePath: /collections/tmp_collection > [junit4] 2> 1115755 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.c.ZkStateReader Load collection config > from:/collections/tmp_collection > [junit4] 2> 1115755 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.c.ZkStateReader path=/collections/tmp_collection configName=conf1 > specified config exists in ZooKeeper > [junit4] 2> 1115755 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: > '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores/collection1/' > [junit4] 2> 1115763 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.Config loaded config solrconfig.xml with version 0 > [junit4] 2> 1115765 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.SolrConfig current version of requestparams : -1 > [junit4] 2> 1115767 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 > [junit4] 2> 1115771 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.Config Loaded SolrConfig: solrconfig.xml > [junit4] 2> 1115772 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml > [junit4] 2> 1115774 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.s.IndexSchema [collection1] Schema name=test > [junit4] 2> 1115825 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.s.IndexSchema default search field in schema is text > [junit4] 2> 1115826 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.s.IndexSchema unique key field: id > [junit4] 2> 1115826 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file > currency.xml > [junit4] 2> 1115828 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file > currency.xml > [junit4] 2> 1115834 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming > default properties: Can't find resource 'configsetprops.json' in classpath or > '/configs/conf1', cwd=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr-core/test/J1 > [junit4] 2> 1115834 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection x:collection1] > o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from > collection tmp_collection > [junit4] 2> 1115834 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore solr.StandardDirectoryFactory > [junit4] 2> 1115834 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new > SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores/collection1/], dataDir=[null] > [junit4] 2> 1115834 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > 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@2b0506 > [junit4] 2> 1115836 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new > directory for /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores/collection1/data > [junit4] 2> 1115836 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: > old=null new=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores/collection1/data/index/ > [junit4] 2> 1115836 WARN (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index > directory > '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores/collection1/data/index' doesn't exist. Creating new > index... > [junit4] 2> 1115837 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new > directory for /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores/collection1/data/index > [junit4] 2> 1115837 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy > RandomMergePolicy wrapping class > org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: > maxMergeAtOnce=35, maxMergeAtOnceExplicit=20, > maxMergedSegmentMB=91.3994140625, floorSegmentMB=0.6083984375, > forceMergeDeletesPctAllowed=7.327476128523527, segmentsPerTier=30.0, > maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 > [junit4] 2> 1115849 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: > commits: num=1 > [junit4] 2> commit{dir=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty- > 001/cores/collection1/data/index,segFN=segments_1,generation=1} > [junit4] 2> 1115849 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 > [junit4] 2> 1115849 INFO (OldIndexDirectoryCleanupThreadForCore- > collection1) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 > x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup > for > core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-001/cores/collection1/data/ > [junit4] 2> 1115851 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating > updateRequestProcessorChain "cdcr-processor-chain" > [junit4] 2> 1115860 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore no > updateRequestProcessorChain defined as default, creating implicit default > [junit4] 2> 1115861 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.h.l.XMLLoader > xsltCacheLifetimeSeconds=60 > [junit4] 2> 1115861 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.h.l.XMLLoader > xsltCacheLifetimeSeconds=60 > [junit4] 2> 1115862 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.h.l.XMLLoader > xsltCacheLifetimeSeconds=60 > [junit4] 2> 1115862 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.h.l.XMLLoader > xsltCacheLifetimeSeconds=60 > [junit4] 2> 1115869 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: > /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/ad > min/segments,/get,/admin/system,/replication,/admin/properties,/config,/ > schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admi > n/ping,/cdcr,/update,/admin/file > [junit4] 2> 1115869 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: > org.apache.solr.search.stats.LocalStatsCache > [junit4] 2> 1115876 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog > implementation: org.apache.solr.update.CdcrUpdateLog > [junit4] 2> 1115876 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: > dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 > maxNumLogsToKeep=10 numVersionBuckets=65536 > [junit4] 2> 1115876 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled > [junit4] 2> 1115876 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled > [junit4] 2> 1115877 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy > RandomMergePolicy wrapping class > org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: > minMergeSize=1000, mergeFactor=24, > maxMergeSize=9223372036854775807, > maxMergeSizeForForcedMerge=9223372036854775807, > calibrateSizeByDeletes=true, maxMergeDocs=2147483647, > maxCFSSegmentSizeMB=8.796093022207999E12, > noCFSRatio=0.7148916059934274] > [junit4] 2> 1115877 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: > commits: num=1 > [junit4] 2> commit{dir=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty- > 001/cores/collection1/data/index,segFN=segments_1,generation=1} > [junit4] 2> 1115877 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 > [junit4] 2> 1115877 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening > Searcher@80e964[collection1] main > [junit4] 2> 1115878 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config > from:/collections/tmp_collection > [junit4] 2> 1115878 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader > path=/collections/tmp_collection configName=conf1 specified config exists > in ZooKeeper > [junit4] 2> 1115878 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up > ZooKeeper-based storage for the RestManager with znodeBase: > /configs/conf1 > [junit4] 2> 1115878 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured > ZooKeeperStorageIO with znodeBase: /configs/conf1 > [junit4] 2> 1115878 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager > with initArgs: {} > [junit4] 2> 1115878 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading > _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 1115878 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found > for znode /configs/conf1/_rest_managed.json > [junit4] 2> 1115879 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at > path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 1115879 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered > ManagedResources > [junit4] 2> 1115879 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be > reserved for 10000 > [junit4] 2> 1115881 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: > /collections/tmp_collection/cdcr/state > [junit4] 2> 1115882 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.h.CdcrBufferStateManager Created znode > /collections/tmp_collection/cdcr/state/buffer > [junit4] 2> 1115884 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.h.CdcrProcessStateManager Created znode > /collections/tmp_collection/cdcr/state/process > [junit4] 2> 1115894 INFO (searcherExecutor-3058-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SolrCore [collection1] Registered new searcher > Searcher@80e964[collection1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1115894 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of > version field to seed version buckets > [junit4] 2> 1115894 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of > _version_ for 65536 version buckets from index > [junit4] 2> 1115894 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > 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> 1115894 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in > index or recent updates, using new clock 1509333929729982464 > [junit4] 2> 1115896 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version > buckets with highest version 1509333929729982464 > [junit4] 2> 1115896 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 > [junit4] 2> 1115896 INFO (coreLoadExecutor-3057-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ c:tmp_collection s:shard1 > r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: > collection1 > [junit4] 2> 1115897 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ZkController Register replica - core:collection1 > address:http://127.0.0.1:50099 collection:tmp_collection shard:shard1 > [junit4] 2> 1115897 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.c.SolrZkClient makePath: > /collections/tmp_collection/leader_elect/shard1/election > [junit4] 2> 1115898 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.LeaderElector Joined leadership election with path: > /collections/tmp_collection/leader_elect/shard1/election/9433337051434189 > 2-core_node1-n_0000000000 > [junit4] 2> 1115898 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ShardLeaderElectionContext Running the leader process for shard > shard1 > [junit4] 2> 1115899 INFO (zkCallback-794-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.c.DistributedQueue > NodeChildrenChanged fired on path /overseer/queue state SyncConnected > [junit4] 2> 1115899 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. > [junit4] 2> 1115899 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync > [junit4] 2> 1115899 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:50099/collection1/ > [junit4] 2> 1115899 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] 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: 1 > [junit4] 2> 1115899 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SyncStrategy Sync Success - now sync replicas to me > [junit4] 2> 1115900 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SyncStrategy http://127.0.0.1:50099/collection1/ has no replicas > [junit4] 2> 1115900 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ShardLeaderElectionContext I am the new leader: > http://127.0.0.1:50099/collection1/ shard1 > [junit4] 2> 1115900 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.c.SolrZkClient makePath: /collections/tmp_collection/leaders/shard1 > [junit4] 2> 1115901 INFO (zkCallback-794-thread-1-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.h.CdcrProcessStateManager > Received new leader state @ tmp_collection:shard1 > [junit4] 2> 1115902 INFO (zkCallback-794-thread-2-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.c.DistributedQueue > NodeChildrenChanged fired on path /overseer/queue state SyncConnected > [junit4] 2> 1115903 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] 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":"http://127.0.0.1:50099";, > [junit4] 2> "core":"collection1", > [junit4] 2> "state":"active"} current state version: 1 > [junit4] 2> 1116004 INFO (zkCallback-794-thread-2-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.c.c.ZkStateReader A cluster > state change: WatchedEvent state:SyncConnected type:NodeDataChanged > path:/clusterstate.json, has occurred - updating... (live nodes size: 1) > [junit4] 2> 1116053 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ZkController We are http://127.0.0.1:50099/collection1/ and leader is > http://127.0.0.1:50099/collection1/ > [junit4] 2> 1116053 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ZkController No LogReplay needed for core=collection1 > baseURL=http://127.0.0.1:50099 > [junit4] 2> 1116053 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ZkController I am the leader, no recovery necessary > [junit4] 2> 1116053 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ZkController publishing state=active > [junit4] 2> 1116053 INFO (coreZkRegister-3051-thread-1-processing- > n:127.0.0.1:50099_ x:collection1 s:shard1 c:tmp_collection r:core_node1) > [n:127.0.0.1:50099_ c:tmp_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ZkController numShards not found on descriptor - reading it from > system property > [junit4] 2> 1116054 INFO (zkCallback-794-thread-2-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.c.DistributedQueue > NodeChildrenChanged fired on path /overseer/queue state SyncConnected > [junit4] 2> 1116054 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] 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":"http://127.0.0.1:50099";, > [junit4] 2> "node_name":"127.0.0.1:50099_", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"active", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} current state version: 2 > [junit4] 2> 1116054 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] > o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ > [junit4] 2> "core":"collection1", > [junit4] 2> "core_node_name":"core_node1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"http://127.0.0.1:50099";, > [junit4] 2> "node_name":"127.0.0.1:50099_", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"active", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} > [junit4] 2> 1116155 INFO (zkCallback-794-thread-2-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.c.c.ZkStateReader A cluster > state change: WatchedEvent state:SyncConnected type:NodeDataChanged > path:/clusterstate.json, has occurred - updating... (live nodes size: 1) > [junit4] 2> 1116338 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties > file to /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores/collection1 > [junit4] 2> 1116339 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 > [junit4] 2> 1116340 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@1839513{/,null,AVAILABLE} > [junit4] 2> 1116341 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.e.j.s.ServerConnector Started > ServerConnector@11626e4{HTTP/1.1}{127.0.0.1:40257} > [junit4] 2> 1116341 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.e.j.s.Server Started @1118328ms > [junit4] 2> 1116341 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: > {shards=shard2, hostContext=/, hostPort=40257, > coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores} > [junit4] 2> 1116341 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.s.SolrDispatchFilter > SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@e2f2a > [junit4] 2> 1116341 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.SolrResourceLoader new > SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr- > trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/' > [junit4] 2> 1116354 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkCredentialsProvider > [junit4] 2> 1116354 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Waiting for > client to connect to ZooKeeper > [junit4] 2> 1116355 INFO (zkCallback-795-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@cffadf > name:ZooKeeperConnection Watcher:127.0.0.1:53819/solr got event > WatchedEvent state:SyncConnected type:None path:null path:null > type:None > [junit4] 2> 1116355 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Client is > connected to ZooKeeper > [junit4] 2> 1116356 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkACLProvider > [junit4] 2> 1116357 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml > from SolrHome (not found in ZooKeeper) > [junit4] 2> 1116357 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.SolrXmlConfig Loading container > configuration from /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/solr.xml > [junit4] 2> 1116360 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.CoresLocator Config-defined core > root directory: /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores > [junit4] 2> 1116360 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.CoreContainer New CoreContainer > 30201896 > [junit4] 2> 1116360 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.CoreContainer Loading cores into > CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/] > [junit4] 2> 1116360 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.CoreContainer loading shared library: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/lib > [junit4] 2> 1116361 WARN (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.SolrResourceLoader Can't find (or > read) directory to add to classloader: lib (resolved as: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/lib). > [junit4] 2> 1116365 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] 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> 1116366 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.u.UpdateShardHandler Creating > UpdateShardHandler HTTP client with params: > socketTimeout=340000&connTimeout=45000&retry=true > [junit4] 2> 1116366 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.l.LogWatcher SLF4J impl is > org.slf4j.impl.Log4jLoggerFactory > [junit4] 2> 1116366 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.l.LogWatcher Registering Log Listener > [Log4j (org.slf4j.impl.Log4jLoggerFactory)] > [junit4] 2> 1116366 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.ZkContainer Zookeeper > client=127.0.0.1:53819/solr > [junit4] 2> 1116367 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.ZkController zkHost includes chroot > [junit4] 2> 1116367 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkCredentialsProvider > [junit4] 2> 1116367 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Waiting for > client to connect to ZooKeeper > [junit4] 2> 1116368 INFO (zkCallback-797-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@181f684 > name:ZooKeeperConnection Watcher:127.0.0.1:53819 got event > WatchedEvent state:SyncConnected type:None path:null path:null > type:None > [junit4] 2> 1116368 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.ConnectionManager Client is > connected to ZooKeeper > [junit4] 2> 1116369 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.a.s.c.c.SolrZkClient Using default > ZkACLProvider > [junit4] 2> 1116369 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] > o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 1116370 INFO (zkCallback-798-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ ] o.a.s.c.c.ConnectionManager > Watcher org.apache.solr.common.cloud.ConnectionManager@31e544 > name:ZooKeeperConnection Watcher:127.0.0.1:53819/solr got event > WatchedEvent state:SyncConnected type:None path:null path:null > type:None > [junit4] 2> 1116370 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] > o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 1116371 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.c.ZkStateReader > Updating cluster state from ZooKeeper... > [junit4] 2> 1117373 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.ZkController > Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40257_ > [junit4] 2> 1117373 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.c.SolrZkClient > makePath: /live_nodes/127.0.0.1:40257_ > [junit4] 2> 1117374 INFO (zkCallback-794-thread-2-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.c.c.ZkStateReader A live > node change: WatchedEvent state:SyncConnected > type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live > nodes size: 1) > [junit4] 2> 1117374 INFO (zkCallback-798-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ ] o.a.s.c.c.ZkStateReader A live > node change: WatchedEvent state:SyncConnected > type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live > nodes size: 1) > [junit4] 2> 1117374 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.Overseer > Overseer (id=null) closing > [junit4] 2> 1117375 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.LeaderElector > Joined leadership election with path: > /overseer_elect/election/94333370514341895-127.0.0.1:40257_- > n_0000000001 > [junit4] 2> 1117375 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.LeaderElector > Watching path /overseer_elect/election/94333370514341892- > 127.0.0.1:50099_-n_0000000000 to know if I could be the leader > [junit4] 2> 1117407 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.CoreContainer > Security conf doesn't exist. Skipping setup for authorization module. > [junit4] 2> 1117407 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.CoreContainer No > authentication plugin used. > [junit4] 2> 1117408 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.CoresLocator > Looking for core definitions underneath /home/jenkins/workspace/Lucene- > Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores > [junit4] 2> 1117408 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.SolrCore Created > CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, > schema=schema.xml, loadOnStartup=true, > configSetProperties=configsetprops.json, > instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores/collection1, collection=tmp_collection, > absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores/collection1/, coreNodeName=, dataDir=data/, shard=} > [junit4] 2> 1117408 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.CoresLocator > Found core collection1 in /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores/collection1/ > [junit4] 2> 1117409 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.c.CoresLocator > Found 1 core definitions > [junit4] 2> 1117409 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.ZkController publishing state=down > [junit4] 2> 1117409 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.ZkController numShards not found on descriptor - reading it from > system property > [junit4] 2> 1117409 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.s.SolrDispatchFilter > user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1 > [junit4] 2> 1117409 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ ] o.a.s.s.SolrDispatchFilter > SolrDispatchFilter.init() done > [junit4] 2> 1117409 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.ZkController Registering watch for collection tmp_collection > [junit4] 2> 1117409 INFO (zkCallback-794-thread-2-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.c.DistributedQueue > NodeChildrenChanged fired on path /overseer/queue state SyncConnected > [junit4] 2> 1117410 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.c.ZkStateReader addZkWatch tmp_collection > [junit4] 2> 1117410 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.c.ZkStateReader Deleting data for tmp_collection > [junit4] 2> 1117410 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] o.a.s.c.Overseer > processMessage: queueSize: 1, message = { > [junit4] 2> "core":"collection1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"http://127.0.0.1:40257";, > [junit4] 2> "node_name":"127.0.0.1:40257_", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} current state version: 3 > [junit4] 2> 1117410 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.ZkController look for our core node name > [junit4] 2> 1117410 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] > o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ > [junit4] 2> "core":"collection1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"http://127.0.0.1:40257";, > [junit4] 2> "node_name":"127.0.0.1:40257_", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junit4] 2> "collection":"tmp_collection", > [junit4] 2> "operation":"state"} > [junit4] 2> 1117410 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] > o.a.s.c.o.ReplicaMutator Collection already exists with numShards=1 > [junit4] 2> 1117410 INFO (OverseerStateUpdate-94333370514341892- > 127.0.0.1:50099_-n_0000000000) [n:127.0.0.1:50099_ ] > o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 > [junit4] 2> 1117512 INFO (zkCallback-798-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ ] o.a.s.c.c.ZkStateReader A cluster > state change: WatchedEvent state:SyncConnected type:NodeDataChanged > path:/clusterstate.json, has occurred - updating... (live nodes size: 2) > [junit4] 2> 1117512 INFO (zkCallback-794-thread-2-processing- > n:127.0.0.1:50099_) [n:127.0.0.1:50099_ ] o.a.s.c.c.ZkStateReader A cluster > state change: WatchedEvent state:SyncConnected type:NodeDataChanged > path:/clusterstate.json, has occurred - updating... (live nodes size: 2) > [junit4] 2> 1118410 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 > [junit4] 2> 1118411 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.ZkController Check for collection zkNode:tmp_collection > [junit4] 2> 1118411 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.ZkController Collection zkNode exists > [junit4] 2> 1118411 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.c.ZkStateReader Load collection config > from:/collections/tmp_collection > [junit4] 2> 1118411 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.c.ZkStateReader path=/collections/tmp_collection configName=conf1 > specified config exists in ZooKeeper > [junit4] 2> 1118411 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: > '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores/collection1/' > [junit4] 2> 1118422 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.Config loaded config solrconfig.xml with version 0 > [junit4] 2> 1118426 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.SolrConfig current version of requestparams : -1 > [junit4] 2> 1118428 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 > [junit4] 2> 1118434 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.Config Loaded SolrConfig: solrconfig.xml > [junit4] 2> 1118435 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml > [junit4] 2> 1118439 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.s.IndexSchema [collection1] Schema name=test > [junit4] 2> 1118497 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.s.IndexSchema default search field in schema is text > [junit4] 2> 1118498 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.s.IndexSchema unique key field: id > [junit4] 2> 1118498 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file > currency.xml > [junit4] 2> 1118500 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file > currency.xml > [junit4] 2> 1118505 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming > default properties: Can't find resource 'configsetprops.json' in classpath or > '/configs/conf1', cwd=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr-core/test/J1 > [junit4] 2> 1118505 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection x:collection1] > o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from > collection tmp_collection > [junit4] 2> 1118505 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.SolrCore solr.StandardDirectoryFactory > [junit4] 2> 1118506 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new > SolrCore at [/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores/collection1/], dataDir=[null] > [junit4] 2> 1118506 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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@2b0506 > [junit4] 2> 1118506 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.CachingDirectoryFactory return new > directory for /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores/collection1/data > [junit4] 2> 1118506 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.SolrCore New index directory detected: > old=null new=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores/collection1/data/index/ > [junit4] 2> 1118506 WARN (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.SolrCore [collection1] Solr index > directory > '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores/collection1/data/index' doesn't exist. Creating new > index... > [junit4] 2> 1118507 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.CachingDirectoryFactory return new > directory for /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores/collection1/data/index > [junit4] 2> 1118507 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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=35, maxMergeAtOnceExplicit=20, > maxMergedSegmentMB=91.3994140625, floorSegmentMB=0.6083984375, > forceMergeDeletesPctAllowed=7.327476128523527, segmentsPerTier=30.0, > maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 > [junit4] 2> 1118544 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: > commits: num=1 > [junit4] 2> commit{dir=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty- > 002/cores/collection1/data/index,segFN=segments_1,generation=1} > [junit4] 2> 1118544 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 > [junit4] 2> 1118544 INFO (OldIndexDirectoryCleanupThreadForCore- > collection1) [n:127.0.0.1:40257_ 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 /home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty-002/cores/collection1/data/ > [junit4] 2> 1118546 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating > updateRequestProcessorChain "cdcr-processor-chain" > [junit4] 2> 1118546 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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> 1118548 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.h.l.XMLLoader > xsltCacheLifetimeSeconds=60 > [junit4] 2> 1118548 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.h.l.XMLLoader > xsltCacheLifetimeSeconds=60 > [junit4] 2> 1118549 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.h.l.XMLLoader > xsltCacheLifetimeSeconds=60 > [junit4] 2> 1118549 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.h.l.XMLLoader > xsltCacheLifetimeSeconds=60 > [junit4] 2> 1118552 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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,/ad > min/segments,/get,/admin/system,/replication,/admin/properties,/config,/ > schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admi > n/ping,/cdcr,/update,/admin/file > [junit4] 2> 1118552 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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> 1118553 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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> 1118553 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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> 1118553 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled > [junit4] 2> 1118553 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled > [junit4] 2> 1118554 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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=24, > maxMergeSize=9223372036854775807, > maxMergeSizeForForcedMerge=9223372036854775807, > calibrateSizeByDeletes=true, maxMergeDocs=2147483647, > maxCFSSegmentSizeMB=8.796093022207999E12, > noCFSRatio=0.7148916059934274] > [junit4] 2> 1118554 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: > commits: num=1 > [junit4] 2> commit{dir=/home/jenkins/workspace/Lucene-Solr-trunk- > Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001/jetty- > 002/cores/collection1/data/index,segFN=segments_1,generation=1} > [junit4] 2> 1118554 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 > [junit4] 2> 1118555 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.s.SolrIndexSearcher Opening > Searcher@12a74aa[collection1] main > [junit4] 2> 1118555 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.c.ZkStateReader Load collection config > from:/collections/tmp_collection > [junit4] 2> 1118555 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.c.c.ZkStateReader > path=/collections/tmp_collection configName=conf1 specified config exists > in ZooKeeper > [junit4] 2> 1118555 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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> 1118555 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.r.ManagedResourceStorage Configured > ZooKeeperStorageIO with znodeBase: /configs/conf1 > [junit4] 2> 1118555 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.r.RestManager Initializing RestManager > with initArgs: {} > [junit4] 2> 1118555 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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> 1118556 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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> 1118556 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ 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> 1118556 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.r.RestManager Initializing 0 registered > ManagedResources > [junit4] 2> 1118556 INFO (coreLoadExecutor-3069-thread-1-processing- > n:127.0.0.1:40257_) [n:127.0.0.1:40257_ c:tmp_collection s:shard1 > r:core_node2 x:collection1] o.a.s.h.ReplicationHandler Commits will be > reserved for 10000 > [junit4] > > [...truncated too long message...] > > 2> at java.lang.Thread.run(Thread.java:745) > [junit4] 2> Caused by: > org.apache.zookeeper.KeeperException$SessionExpiredException: > KeeperErrorCode = Session expired for /overseer > [junit4] 2> at > org.apache.zookeeper.KeeperException.create(KeeperException.java:127) > [junit4] 2> at > org.apache.zookeeper.KeeperException.create(KeeperException.java:51) > [junit4] 2> at > org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783) > [junit4] 2> at > org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:388) > [junit4] 2> at > org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:385) > [junit4] 2> at > org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecu > tor.java:61) > [junit4] 2> at > org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:385) > [junit4] 2> at > org.apache.solr.cloud.Overseer.createOverseerNode(Overseer.java:932) > [junit4] 2> ... 20 more > [junit4] 2> 1599518 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.e.j.s.ServerConnector Stopped > ServerConnector@11626e4{HTTP/1.1}{127.0.0.1:0} > [junit4] 2> 1599518 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [ ] o.e.j.s.h.ContextHandler Stopped > o.e.j.s.ServletContextHandler@1839513{/,null,UNAVAILABLE} > [junit4] 2> 1599519 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ c:source_collection s:shard1 > r:core_node1 x:source_collection_shard1_replica1] o.a.s.SolrTestCaseJ4 > ###Ending doTest > [junit4] 2> 1599519 INFO (TEST-CdcrReplicationHandlerTest.doTest- > seed#[C35243D1951C71D7]) [n:127.0.0.1:40257_ c:source_collection s:shard1 > r:core_node1 x:source_collection_shard1_replica1] o.a.s.c.ZkTestServer > connecting to 127.0.0.1:53819 53819 > [junit4] 2> 1599553 INFO (Thread-2638) [ ] o.a.s.c.ZkTestServer > connecting to 127.0.0.1:53819 53819 > [junit4] 2> 1599555 WARN (Thread-2638) [ ] o.a.s.c.ZkTestServer Watch > limit violations: > [junit4] 2> Maximum concurrent create/delete watches above limit: > [junit4] 2> > [junit4] 2> 48 /solr/aliases.json > [junit4] 2> 12 /solr/configs/conf1 > [junit4] 2> 4 /solr/security.json > [junit4] 2> 4 > /solr/collections/source_collection/leaders/shard1 > [junit4] 2> 2 /solr/collections/tmp_collection/leaders/shard1 > [junit4] 2> > [junit4] 2> Maximum concurrent data watches above limit: > [junit4] 2> > [junit4] 2> 48 /solr/clusterstate.json > [junit4] 2> 10 > /solr/collections/source_collection/cdcr/state/buffer > [junit4] 2> 10 > /solr/collections/source_collection/cdcr/state/process > [junit4] 2> 7 /solr/collections/source_collection/state.json > [junit4] 2> 3 > /solr/collections/source_collection/leader_elect/shard1/election/943 > 33370514341895-core_node2-n_0000000000 > [junit4] 2> 2 > /solr/collections/tmp_collection/cdcr/state/buffer > [junit4] 2> 2 /solr/overseer_elect/election/94333370514341895- > 127.0.0.1:40257_-n_0000000001 > [junit4] 2> 2 > /solr/collections/source_collection/leader_elect/shard1/election/943 > 33370514341895-core_node1-n_0000000000 > [junit4] 2> 2 > /solr/collections/tmp_collection/cdcr/state/process > [junit4] 2> > [junit4] 2> Maximum concurrent children watches above limit: > [junit4] 2> > [junit4] 2> 46 /solr/live_nodes > [junit4] 2> 2 /solr/overseer/queue > [junit4] 2> 2 /solr/overseer/collection-queue-work > [junit4] 2> > [junit4] 2> NOTE: reproduce with: ant test - > Dtestcase=CdcrReplicationHandlerTest -Dtests.method=doTest - > Dtests.seed=C35243D1951C71D7 -Dtests.multiplier=3 -Dtests.slow=true - > Dtests.locale=ja -Dtests.timezone=Australia/Melbourne -Dtests.asserts=true > -Dtests.file.encoding=US-ASCII > [junit4] FAILURE 485s J1 | CdcrReplicationHandlerTest.doTest <<< > [junit4] > Throwable #1: java.lang.AssertionError: There are still nodes > recoverying - waited for 330 seconds > [junit4] > at > __randomizedtesting.SeedInfo.seed([C35243D1951C71D7:6416FB75F8A7626 > E]:0) > [junit4] > at > org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish( > AbstractDistribZkTestBase.java:172) > [junit4] > at > org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish( > AbstractDistribZkTestBase.java:133) > [junit4] > at > org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish( > AbstractDistribZkTestBase.java:128) > [junit4] > at > org.apache.solr.cloud.BaseCdcrDistributedZkTest.waitForRecoveriesToFinish > (BaseCdcrDistributedZkTest.java:465) > [junit4] > at > org.apache.solr.cloud.BaseCdcrDistributedZkTest.clearSourceCollection(Base > CdcrDistributedZkTest.java:319) > [junit4] > at > org.apache.solr.cloud.CdcrReplicationHandlerTest.doTestPartialReplicationWi > thTruncatedTlog(CdcrReplicationHandlerTest.java:121) > [junit4] > at > org.apache.solr.cloud.CdcrReplicationHandlerTest.doTest(CdcrReplicationHa > ndlerTest.java:52) > [junit4] > at > org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFi > xedStatement.callStatement(BaseDistributedSearchTestCase.java:963) > [junit4] > at > org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsSt > atement.evaluate(BaseDistributedSearchTestCase.java:938) > [junit4] > at java.lang.Thread.run(Thread.java:745) > [junit4] 2> 1599562 INFO (SUITE-CdcrReplicationHandlerTest- > seed#[C35243D1951C71D7]-worker) [n:127.0.0.1:40257_ c:source_collection > s:shard1 r:core_node1 x:source_collection_shard1_replica1] > o.a.s.SolrTestCaseJ4 ###deleteCore > [junit4] 2> NOTE: leaving temporary files on disk at: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr- > core/test/J1/temp/solr.cloud.CdcrReplicationHandlerTest_C35243D1951C71D > 7-001 > [junit4] 2> Aug 12, 2015 9:04:47 PM > com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks > [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked > thread(s). > [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): > {_version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat > (Lucene50(blocksize=128))), id=PostingsFormat(name=Memory doPackFST= > true)}, docValues:{}, sim=DefaultSimilarity, locale=ja, > timezone=Australia/Melbourne > [junit4] 2> NOTE: Linux 3.16.0-44-generic i386/Oracle Corporation > 1.8.0_60- > ea (32-bit)/cpus=12,threads=1,free=91120520,total=446169088 > [junit4] 2> NOTE: All tests run in this JVM: [TestRTimerTree, > TestAtomicUpdateErrorCases, TestSolrXml, SecurityConfHandlerTest, > DistribDocExpirationUpdateProcessorTest, TestSolrCloudWithKerberosAlt, > TestSolrCoreProperties, SolrPluginUtilsTest, TestElisionMultitermQuery, > TestReRankQParserPlugin, DocumentAnalysisRequestHandlerTest, > TestFiltering, TestLRUCache, TestRestManager, DistanceFunctionTest, > OutOfBoxZkACLAndCredentialsProvidersTest, TestStressUserVersions, > ChaosMonkeySafeLeaderTest, TestManagedSynonymFilterFactory, > ShardSplitTest, TestFastLRUCache, HdfsChaosMonkeySafeLeaderTest, > DeleteShardTest, TestPivotHelperCode, > ConcurrentDeleteAndCreateCollectionTest, EchoParamsTest, > AnalysisAfterCoreReloadTest, TestSchemaResource, > TestFoldingMultitermQuery, DistanceUnitsTest, > TestBlendedInfixSuggestions, TestDownShardTolerantSearch, > AlternateDirectoryTest, DirectSolrConnectionTest, > FieldAnalysisRequestHandlerTest, HdfsBasicDistributedZk2Test, > MultiThreadedOCPTest, OverseerRolesTest, > AsyncCallRequestStatusResponseTest, AsyncMigrateRouteKeyTest, > TestReloadDeadlock, TestDistributedGrouping, UpdateParamsTest, > BlockDirectoryTest, TestCopyFieldCollectionResource, TestCharFilters, > DistributedMLTComponentTest, SpellingQueryConverterTest, > DocValuesMultiTest, TestFieldCollectionResource, SolrTestCaseJ4Test, > TestHighFrequencyDictionaryFactory, ResourceLoaderTest, TestLFUCache, > TestAddFieldRealTimeGet, ClusterStateUpdateTest, TestRestoreCore, > DistributedTermsComponentTest, ZkControllerTest, > TestSolrConfigHandlerConcurrent, SpellCheckComponentTest, > IndexSchemaRuntimeFieldTest, BadComponentTest, FileUtilsTest, > TestManagedSchemaDynamicFieldResource, TestCoreContainer, > TestFreeTextSuggestions, CustomCollectionTest, DataDrivenBlockJoinTest, > TestAnalyzedSuggestions, ShardRoutingCustomTest, TestFastWriter, > ReturnFieldsTest, SpatialFilterTest, DistributedExpandComponentTest, > CopyFieldTest, TestSolrDynamicMBean, CachingDirectoryFactoryTest, > WordBreakSolrSpellCheckerTest, TestHashPartitioner, HdfsThreadLeakTest, > SmileWriterTest, JSONWriterTest, > StatelessScriptUpdateProcessorFactoryTest, DocumentBuilderTest, > SolrCmdDistributorTest, TestObjectReleaseTracker, > CollectionsAPIDistributedZkTest, ShardRoutingTest, LeaderElectionTest, > ZkCLITest, TestDistributedSearch, AssignTest, CdcrReplicationHandlerTest] > [junit4] Completed [459/540] on J1 in 485.87s, 1 test, 1 failure <<< > FAILURES! > > [...truncated 253 lines...] > BUILD FAILED > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:769: The > following error occurred while executing this line: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:713: The > following error occurred while executing this line: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:59: The > following error occurred while executing this line: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:233: The > following error occurred while executing this line: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common- > build.xml:514: The following error occurred while executing this line: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common- > build.xml:1429: The following error occurred while executing this line: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common- > build.xml:984: There were test failures: 540 suites, 2128 tests, 1 failure, 68 > ignored (32 assumptions) > > Total time: 48 minutes 53 seconds > Build step 'Invoke Ant' marked build as failure > Archiving artifacts > [WARNINGS] Skipping publisher since build result is FAILURE > 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]
