I've done a little looking at this, and the necessary precursor seems to be the doTestConfigUpdate test before the delete test. So far (and I haven't looked too deeply), just commenting out that test causes the delete to succeed. As does putting a 15 second sleep right after doTestConfigUpdate. Not committable solution, but it does give me a clue....
For exploratory purposes I put in a loop to see if the state of any of the nodes were ever not "active" (waitForRecoveriesToFinish called in a 150 count loop with a sleep of 100 ms each loop) after the doTestConfigUpdate call and the answer is "no". Mostly just asking whether this rings trips any alarms anyone else has seen in parallel to diving deeper. On Tue, Oct 13, 2015 at 2:49 PM, Policeman Jenkins Server <[email protected]> wrote: > Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/14519/ > Java: 64bit/jdk1.8.0_60 -XX:+UseCompressedOops -XX:+UseParallelGC > > 1 tests failed. > FAILED: > org.apache.solr.cloud.SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection > > Error Message: > Delete action failed! > > Stack Trace: > java.lang.AssertionError: Delete action failed! > at > __randomizedtesting.SeedInfo.seed([1E4EBBB2B14DFD9C:D2D89DD8022443A]:0) > at org.junit.Assert.fail(Assert.java:93) > at org.junit.Assert.assertTrue(Assert.java:43) > at > org.apache.solr.cloud.SolrCloudExampleTest.doTestDeleteAction(SolrCloudExampleTest.java:169) > at > org.apache.solr.cloud.SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection(SolrCloudExampleTest.java:145) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:497) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1665) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:864) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:900) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:914) > at > org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) > at > org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) > at > org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:873) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:775) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:809) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:820) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) > at > org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at java.lang.Thread.run(Thread.java:745) > > > > > Build Log: > [...truncated 9824 lines...] > [junit4] Suite: org.apache.solr.cloud.SolrCloudExampleTest > [junit4] 2> Creating dataDir: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/init-core-data-001 > [junit4] 2> 471742 INFO > (SUITE-SolrCloudExampleTest-seed#[1E4EBBB2B14DFD9C]-worker) [ ] > o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) > [junit4] 2> 471742 INFO > (SUITE-SolrCloudExampleTest-seed#[1E4EBBB2B14DFD9C]-worker) [ ] > o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / > [junit4] 2> 471744 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER > [junit4] 2> 471745 INFO (Thread-1283) [ ] o.a.s.c.ZkTestServer > client port:0.0.0.0/0.0.0.0:0 > [junit4] 2> 471745 INFO (Thread-1283) [ ] o.a.s.c.ZkTestServer > Starting server > [junit4] 2> 471845 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.ZkTestServer start zk server on port:39969 > [junit4] 2> 471845 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider > [junit4] 2> 471846 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 471847 INFO (zkCallback-438-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@4dbca28d > name:ZooKeeperConnection Watcher:127.0.0.1:39969 got event WatchedEvent > state:SyncConnected type:None path:null path:null type:None > [junit4] 2> 471847 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 471848 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider > [junit4] 2> 471848 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /solr > [junit4] 2> 471849 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider > [junit4] 2> 471850 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 471850 INFO (zkCallback-439-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@d860e7d > name:ZooKeeperConnection Watcher:127.0.0.1:39969/solr got event WatchedEvent > state:SyncConnected type:None path:null path:null type:None > [junit4] 2> 471850 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 471851 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider > [junit4] 2> 471851 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 > [junit4] 2> 471852 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards > [junit4] 2> 471852 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection > [junit4] 2> 471853 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: > /collections/control_collection/shards > [junit4] 2> 471853 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml > to /configs/conf1/solrconfig.xml > [junit4] 2> 471853 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml > [junit4] 2> 471854 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.AbstractZkTestCase put > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml > to /configs/conf1/schema.xml > [junit4] 2> 471854 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml > [junit4] 2> 471855 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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> 471855 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/solrconfig.snippet.randomindexconfig.xml > [junit4] 2> 471855 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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> 471856 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt > [junit4] 2> 471856 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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> 471857 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt > [junit4] 2> 471857 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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> 471857 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml > [junit4] 2> 471858 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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> 471858 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml > [junit4] 2> 471858 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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> 471858 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/open-exchange-rates.json > [junit4] 2> 471859 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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> 471859 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: > /configs/conf1/mapping-ISOLatin1Accent.txt > [junit4] 2> 471860 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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> 471860 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt > [junit4] 2> 471860 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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> 471860 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt > [junit4] 2> 471923 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1 > [junit4] 2> 471924 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.e.j.s.Server jetty-9.2.13.v20150730 > [junit4] 2> 471925 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@215b7bd2{/,null,AVAILABLE} > [junit4] 2> 471927 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.e.j.s.ServerConnector Started > ServerConnector@13b6c115{HTTP/1.1}{127.0.0.1:44193} > [junit4] 2> 471927 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.e.j.s.Server Started @475124ms > [junit4] 2> 471927 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: > {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/tempDir-001/control/data, > hostContext=/, hostPort=44193, > coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores} > [junit4] 2> 471927 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): > sun.misc.Launcher$AppClassLoader@4e0e2f2a > [junit4] 2> 471927 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: > '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/' > [junit4] 2> 471927 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr > (NoInitialContextEx) > [junit4] 2> 471927 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not > find system property or JNDI) > [junit4] 2> 471937 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider > [junit4] 2> 471937 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 471937 INFO (zkCallback-440-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@33d7000 > name:ZooKeeperConnection Watcher:127.0.0.1:39969/solr got event WatchedEvent > state:SyncConnected type:None path:null path:null type:None > [junit4] 2> 471938 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 471938 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider > [junit4] 2> 471938 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found > in ZooKeeper) > [junit4] 2> 471938 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.SolrXmlConfig Loading container configuration from > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/solr.xml > [junit4] 2> 471941 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.CoresLocator Config-defined core root directory: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores > [junit4] 2> 471942 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.CoreContainer New CoreContainer 92671834 > [junit4] 2> 471942 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer > [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/] > [junit4] 2> 471942 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.CoreContainer loading shared library: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/lib > [junit4] 2> 471942 WARN > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/lib). > [junit4] 2> 471945 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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> 471946 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client > with params: socketTimeout=340000&connTimeout=45000&retry=true > [junit4] 2> 471946 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory > [junit4] 2> 471946 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j > (org.slf4j.impl.Log4jLoggerFactory)] > [junit4] 2> 471946 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39969/solr > [junit4] 2> 471946 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.ZkController zkHost includes chroot > [junit4] 2> 471946 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider > [junit4] 2> 471947 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 471947 INFO (zkCallback-442-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@4a03ce5e > name:ZooKeeperConnection Watcher:127.0.0.1:39969 got event WatchedEvent > state:SyncConnected type:None path:null path:null type:None > [junit4] 2> 471948 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 471948 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider > [junit4] 2> 471949 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.ConnectionManager Waiting for client to > connect to ZooKeeper > [junit4] 2> 471950 INFO > (zkCallback-443-thread-1-processing-n:127.0.0.1:44193_) [n:127.0.0.1:44193_ > ] o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@7acf3c09 > name:ZooKeeperConnection Watcher:127.0.0.1:39969/solr got event WatchedEvent > state:SyncConnected type:None path:null path:null type:None > [junit4] 2> 471950 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.ConnectionManager Client is connected to > ZooKeeper > [junit4] 2> 471950 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue > [junit4] 2> 471951 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: > /overseer/collection-queue-work > [junit4] 2> 471951 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: > /overseer/collection-map-running > [junit4] 2> 471952 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: > /overseer/collection-map-completed > [junit4] 2> 471952 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: > /overseer/collection-map-failure > [junit4] 2> 471953 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes > [junit4] 2> 471953 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json > [junit4] 2> 471954 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json > [junit4] 2> 471954 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: /security.json > [junit4] 2> 471954 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.ZkController Register node as live in > ZooKeeper:/live_nodes/127.0.0.1:44193_ > [junit4] 2> 471954 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: > /live_nodes/127.0.0.1:44193_ > [junit4] 2> 471954 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect > [junit4] 2> 471955 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: > /overseer_elect/election > [junit4] 2> 471955 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.Overseer Overseer (id=null) closing > [junit4] 2> 471955 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.LeaderElector Joined leadership election > with path: > /overseer_elect/election/94684562057068548-127.0.0.1:44193_-n_0000000000 > [junit4] 2> 471955 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.OverseerElectionContext I am going to be > the leader 127.0.0.1:44193_ > [junit4] 2> 471955 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: > /overseer_elect/leader > [junit4] 2> 471956 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.Overseer Overseer > (id=94684562057068548-127.0.0.1:44193_-n_0000000000) starting > [junit4] 2> 471956 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.SolrZkClient makePath: > /overseer/queue-work > [junit4] 2> 471958 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting > OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 > autoReplicaFailoverWaitAfterExpiration=30000 > autoReplicaFailoverBadNodeExpiration=60000 > [junit4] 2> 471958 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.c.ZkStateReader Updating cluster state from > ZooKeeper... > [junit4] 2> 471958 INFO > (OverseerCollectionConfigSetProcessor-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.OverseerTaskProcessor Process current queue > of overseer operations > [junit4] 2> 471958 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.Overseer Starting to work on the main queue > [junit4] 2> 471971 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.CoreContainer Security conf doesn't exist. > Skipping setup for authorization module. > [junit4] 2> 471971 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.CoreContainer No authentication plugin used. > [junit4] 2> 471972 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.CoresLocator Looking for core definitions > underneath > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores > [junit4] 2> 471972 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] 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/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1, > collection=control_collection, > absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1/, > coreNodeName=, dataDir=data/, shard=} > [junit4] 2> 471972 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.CoresLocator Found core collection1 in > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1/ > [junit4] 2> 471972 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.c.CoresLocator Found 1 core definitions > [junit4] 2> 471973 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.s.SolrDispatchFilter > user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1 > [junit4] 2> 471973 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:44193_ ] o.a.s.s.SolrDispatchFilter > SolrDispatchFilter.init() done > [junit4] 2> 471973 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.c.ZkController publishing state=down > [junit4] 2> 471973 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.c.ZkController numShards not found on descriptor - reading it from > system property > [junit4] 2> 471973 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.c.ZkController look for our core node name > [junit4] 2> 471973 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] 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:44193";, > [junit4] 2> "node_name":"127.0.0.1:44193_", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junit4] 2> "collection":"control_collection", > [junit4] 2> "operation":"state"} current state version: 0 > [junit4] 2> 471974 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] 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:44193";, > [junit4] 2> "node_name":"127.0.0.1:44193_", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junit4] 2> "collection":"control_collection", > [junit4] 2> "operation":"state"} > [junit4] 2> 471974 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.o.ClusterStateMutator building a new cName: > control_collection > [junit4] 2> 471974 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard > shard=shard1 > [junit4] 2> 471975 INFO > (zkCallback-443-thread-1-processing-n:127.0.0.1:44193_) [n:127.0.0.1:44193_ > ] 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> 472974 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 > [junit4] 2> 472974 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.c.ZkController Check for collection zkNode:control_collection > [junit4] 2> 472974 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.c.ZkController Collection zkNode exists > [junit4] 2> 472974 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.c.c.ZkStateReader Load collection config > from:/collections/control_collection > [junit4] 2> 472974 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 > specified config exists in ZooKeeper > [junit4] 2> 472974 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1/' > [junit4] 2> 472975 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) > [junit4] 2> 472975 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find > system property or JNDI) > [junit4] 2> 472983 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] o.a.s.c.Config > loaded config solrconfig.xml with version 0 > [junit4] 2> 472987 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] o.a.s.c.SolrConfig > current version of requestparams : -1 > [junit4] 2> 472990 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] o.a.s.c.SolrConfig > Using Lucene MatchVersion: 6.0.0 > [junit4] 2> 472995 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] o.a.s.c.Config > Loaded SolrConfig: solrconfig.xml > [junit4] 2> 472996 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] o.a.s.s.IndexSchema > Reading Solr Schema from /configs/conf1/schema.xml > [junit4] 2> 472999 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] o.a.s.s.IndexSchema > [collection1] Schema name=test > [junit4] 2> 473085 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.s.OpenExchangeRatesOrgProvider Initialized with > rates=open-exchange-rates.json, refreshInterval=1440. > [junit4] 2> 473088 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] o.a.s.s.IndexSchema > default search field in schema is text > [junit4] 2> 473089 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] o.a.s.s.IndexSchema > unique key field: id > [junit4] 2> 473093 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file > currency.xml > [junit4] 2> 473094 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file > currency.xml > [junit4] 2> 473095 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from > open-exchange-rates.json > [junit4] 2> 473095 WARN > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE > [junit4] 2> 473095 WARN > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING > [junit4] 2> 473095 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from > open-exchange-rates.json > [junit4] 2> 473096 WARN > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE > [junit4] 2> 473096 WARN > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING > [junit4] 2> 473096 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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> 473096 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection x:collection1] > o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration > from collection control_collection > [junit4] 2> 473096 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory > [junit4] 2> 473096 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1/], > dataDir=[null] > [junit4] 2> 473097 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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@3d7c43df > [junit4] 2> 473097 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1/data > [junit4] 2> 473097 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1/data/index/ > [junit4] 2> 473097 WARN > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1/data/index' > doesn't exist. Creating new index... > [junit4] 2> 473097 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1/data/index > [junit4] 2> 473097 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class > org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: > maxMergeAtOnce=28, maxMergeAtOnceExplicit=29, > maxMergedSegmentMB=78.626953125, floorSegmentMB=1.63671875, > forceMergeDeletesPctAllowed=3.0870172493254975, segmentsPerTier=13.0, > maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 > [junit4] 2> 473098 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 > [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@998f02d > lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33711a40),segFN=segments_1,generation=1} > [junit4] 2> 473098 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SolrCore newest commit generation = 1 > [junit4] 2> 473098 INFO > (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44193_ > c:control_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/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1/data/ > [junit4] 2> 473098 WARN > (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.DirectoryFactory > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/control-001/cores/collection1/data/ > does not point to a valid data directory; skipping clean-up of old index > directories. > [junit4] 2> 473100 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain > "nodistrib" > [junit4] 2> 473100 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain > "dedupe" > [junit4] 2> 473100 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.p.UpdateRequestProcessorChain inserting > DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" > [junit4] 2> 473100 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain > "stored_sig" > [junit4] 2> 473100 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.p.UpdateRequestProcessorChain inserting > DistributedUpdateProcessorFactory into updateRequestProcessorChain > "stored_sig" > [junit4] 2> 473100 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain > "distrib-dup-test-chain-explicit" > [junit4] 2> 473101 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain > "distrib-dup-test-chain-implicit" > [junit4] 2> 473101 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.p.UpdateRequestProcessorChain inserting > DistributedUpdateProcessorFactory into updateRequestProcessorChain > "distrib-dup-test-chain-implicit" > [junit4] 2> 473101 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating > implicit default > [junit4] 2> 473102 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 473102 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 473103 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 473103 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 > [junit4] 2> 473105 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file > [junit4] 2> 473105 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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> 473106 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.UpdateHandler Using UpdateLog implementation: > org.apache.solr.update.UpdateLog > [junit4] 2> 473106 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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> 473106 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SolrCore Hard AutoCommit: disabled > [junit4] 2> 473106 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SolrCore Soft AutoCommit: disabled > [junit4] 2> 473106 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class > org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: > maxMergeAtOnce=13, maxMergeAtOnceExplicit=28, > maxMergedSegmentMB=57.4990234375, floorSegmentMB=0.365234375, > forceMergeDeletesPctAllowed=22.15478619058614, segmentsPerTier=35.0, > maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 > [junit4] 2> 473107 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 > [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@998f02d > lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33711a40),segFN=segments_1,generation=1} > [junit4] 2> 473107 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SolrCore newest commit generation = 1 > [junit4] 2> 473107 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.s.SolrIndexSearcher Opening Searcher@69411c92[collection1] main > [junit4] 2> 473107 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.c.ZkStateReader Load collection config > from:/collections/control_collection > [junit4] 2> 473107 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 > specified config exists in ZooKeeper > [junit4] 2> 473107 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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> 473107 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: > /configs/conf1 > [junit4] 2> 473107 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.r.RestManager Initializing RestManager with initArgs: {} > [junit4] 2> 473107 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using > ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 473107 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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> 473108 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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> 473108 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.r.RestManager Initializing 0 registered ManagedResources > [junit4] 2> 473108 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.h.ReplicationHandler Commits will be reserved for 10000 > [junit4] 2> 473108 INFO > (searcherExecutor-1573-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore > [collection1] Registered new searcher Searcher@69411c92[collection1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 473108 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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> 473108 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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> 473108 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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> 473108 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_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 1514952994341257216 > [junit4] 2> 473110 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version > 1514952994341257216 > [junit4] 2> 473110 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ZkController watch zkdir /configs/conf1 > [junit4] 2> 473110 INFO > (coreLoadExecutor-1572-thread-1-processing-n:127.0.0.1:44193_) > [n:127.0.0.1:44193_ c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.CoreContainer registering core: collection1 > [junit4] 2> 473110 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ZkController Register replica - core:collection1 > address:http://127.0.0.1:44193 collection:control_collection shard:shard1 > [junit4] 2> 473110 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.c.SolrZkClient makePath: > /collections/control_collection/leader_elect/shard1/election > [junit4] 2> 473112 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.LeaderElector Joined leadership election with path: > /collections/control_collection/leader_elect/shard1/election/94684562057068548-core_node1-n_0000000000 > [junit4] 2> 473112 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 > [junit4] 2> 473112 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. > [junit4] 2> 473112 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync > [junit4] 2> 473112 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:44193/collection1/ > [junit4] 2> 473113 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SyncStrategy Sync Success - now sync replicas to me > [junit4] 2> 473113 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.Overseer processMessage: queueSize: 1, > message = { > [junit4] 2> "operation":"leader", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"control_collection"} current state version: 1 > [junit4] 2> 473113 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.SyncStrategy http://127.0.0.1:44193/collection1/ has no replicas > [junit4] 2> 473113 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ShardLeaderElectionContext I am the new leader: > http://127.0.0.1:44193/collection1/ shard1 > [junit4] 2> 473113 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.c.SolrZkClient makePath: > /collections/control_collection/leaders/shard1 > [junit4] 2> 473114 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node > [junit4] 2> 473115 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.Overseer processMessage: queueSize: 1, > message = { > [junit4] 2> "operation":"leader", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"control_collection", > [junit4] 2> "base_url":"http://127.0.0.1:44193";, > [junit4] 2> "core":"collection1", > [junit4] 2> "state":"active"} current state version: 1 > [junit4] 2> 473216 INFO > (zkCallback-443-thread-1-processing-n:127.0.0.1:44193_) [n:127.0.0.1:44193_ > ] 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> 473265 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ZkController We are http://127.0.0.1:44193/collection1/ and leader is > http://127.0.0.1:44193/collection1/ > [junit4] 2> 473265 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_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:44193 > [junit4] 2> 473265 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ZkController I am the leader, no recovery necessary > [junit4] 2> 473265 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_collection s:shard1 r:core_node1 x:collection1] > o.a.s.c.ZkController publishing state=active > [junit4] 2> 473265 INFO > (coreZkRegister-1566-thread-1-processing-n:127.0.0.1:44193_ x:collection1 > s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44193_ > c:control_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> 473266 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] 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:44193";, > [junit4] 2> "node_name":"127.0.0.1:44193_", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"active", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"control_collection", > [junit4] 2> "operation":"state"} current state version: 2 > [junit4] 2> 473266 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] 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:44193";, > [junit4] 2> "node_name":"127.0.0.1:44193_", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"active", > [junit4] 2> "shard":"shard1", > [junit4] 2> "collection":"control_collection", > [junit4] 2> "operation":"state"} > [junit4] 2> 473367 INFO > (zkCallback-443-thread-2-processing-n:127.0.0.1:44193_) [n:127.0.0.1:44193_ > ] 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> 473474 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider > [junit4] 2> 473474 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 473475 INFO (zkCallback-445-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@6a4c823 > name:ZooKeeperConnection Watcher:127.0.0.1:39969/solr got event WatchedEvent > state:SyncConnected type:None path:null path:null type:None > [junit4] 2> 473476 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 473476 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider > [junit4] 2> 473476 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... > [junit4] 2> 473477 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause > connection loss:false > [junit4] 2> 473477 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with > stateFormat=2 > [junit4] 2> 473477 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider > [junit4] 2> 473478 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 473478 INFO (zkCallback-446-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@4669e7c > name:ZooKeeperConnection Watcher:127.0.0.1:39969/solr got event WatchedEvent > state:SyncConnected type:None path:null path:null type:None > [junit4] 2> 473478 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 473478 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider > [junit4] 2> 473479 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.Overseer processMessage: queueSize: 1, > message = { > [junit4] 2> "operation":"create", > [junit4] 2> "name":"collection1", > [junit4] 2> "numShards":"2", > [junit4] 2> "stateFormat":"2"} current state version: 3 > [junit4] 2> 473479 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.o.ClusterStateMutator building a new cName: > collection1 > [junit4] 2> 473528 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/cores/collection1 > [junit4] 2> 473529 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001 > [junit4] 2> 473529 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.e.j.s.Server jetty-9.2.13.v20150730 > [junit4] 2> 473530 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@74832b73{/,null,AVAILABLE} > [junit4] 2> 473530 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.e.j.s.ServerConnector Started > ServerConnector@2ce7510{HTTP/1.1}{127.0.0.1:56340} > [junit4] 2> 473530 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.e.j.s.Server Started @476728ms > [junit4] 2> 473530 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: > {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/tempDir-001/jetty1, > solrconfig=solrconfig.xml, hostContext=/, hostPort=56340, > coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/cores} > [junit4] 2> 473531 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): > sun.misc.Launcher$AppClassLoader@4e0e2f2a > [junit4] 2> 473531 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: > '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/' > [junit4] 2> 473531 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr > (NoInitialContextEx) > [junit4] 2> 473531 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not > find system property or JNDI) > [junit4] 2> 473540 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider > [junit4] 2> 473541 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 473542 INFO (zkCallback-447-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@30928678 > name:ZooKeeperConnection Watcher:127.0.0.1:39969/solr got event WatchedEvent > state:SyncConnected type:None path:null path:null type:None > [junit4] 2> 473542 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 473542 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider > [junit4] 2> 473542 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found > in ZooKeeper) > [junit4] 2> 473542 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.SolrXmlConfig Loading container configuration from > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/solr.xml > [junit4] 2> 473546 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.CoresLocator Config-defined core root directory: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/cores > [junit4] 2> 473546 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.CoreContainer New CoreContainer 1434758141 > [junit4] 2> 473546 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer > [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/] > [junit4] 2> 473546 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.CoreContainer loading shared library: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/lib > [junit4] 2> 473547 WARN > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/lib). > [junit4] 2> 473554 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] 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> 473555 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client > with params: socketTimeout=340000&connTimeout=45000&retry=true > [junit4] 2> 473555 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory > [junit4] 2> 473555 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j > (org.slf4j.impl.Log4jLoggerFactory)] > [junit4] 2> 473555 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39969/solr > [junit4] 2> 473555 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.ZkController zkHost includes chroot > [junit4] 2> 473555 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider > [junit4] 2> 473555 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 473556 INFO (zkCallback-449-thread-1) [ ] > o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@4b7641ff > name:ZooKeeperConnection Watcher:127.0.0.1:39969 got event WatchedEvent > state:SyncConnected type:None path:null path:null type:None > [junit4] 2> 473557 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 473557 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider > [junit4] 2> 473558 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.c.ConnectionManager Waiting for client to > connect to ZooKeeper > [junit4] 2> 473559 INFO > (zkCallback-450-thread-1-processing-n:127.0.0.1:56340_) [n:127.0.0.1:56340_ > ] o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@2afe0347 > name:ZooKeeperConnection Watcher:127.0.0.1:39969/solr got event WatchedEvent > state:SyncConnected type:None path:null path:null type:None > [junit4] 2> 473559 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.c.ConnectionManager Client is connected to > ZooKeeper > [junit4] 2> 473562 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.c.ZkStateReader Updating cluster state from > ZooKeeper... > [junit4] 2> 473581 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.o.ZkStateWriter going to create_collection > /collections/collection1/state.json > [junit4] 2> 474565 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.ZkController Register node as live in > ZooKeeper:/live_nodes/127.0.0.1:56340_ > [junit4] 2> 474565 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.c.SolrZkClient makePath: > /live_nodes/127.0.0.1:56340_ > [junit4] 2> 474565 INFO > (zkCallback-443-thread-2-processing-n:127.0.0.1:44193_) [n:127.0.0.1:44193_ > ] 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> 474565 INFO > (zkCallback-450-thread-1-processing-n:127.0.0.1:56340_) [n:127.0.0.1:56340_ > ] 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> 474565 INFO (zkCallback-445-thread-1) [ ] > 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> 474566 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.Overseer Overseer (id=null) closing > [junit4] 2> 474567 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.LeaderElector Joined leadership election > with path: > /overseer_elect/election/94684562057068553-127.0.0.1:56340_-n_0000000001 > [junit4] 2> 474567 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.LeaderElector Watching path > /overseer_elect/election/94684562057068548-127.0.0.1:44193_-n_0000000000 to > know if I could be the leader > [junit4] 2> 474577 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.CoreContainer Security conf doesn't exist. > Skipping setup for authorization module. > [junit4] 2> 474577 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.CoreContainer No authentication plugin used. > [junit4] 2> 474578 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.CoresLocator Looking for core definitions > underneath > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/cores > [junit4] 2> 474578 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] 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/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/cores/collection1, > collection=collection1, > absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/cores/collection1/, > coreNodeName=, dataDir=data/, shard=} > [junit4] 2> 474578 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.CoresLocator Found core collection1 in > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/cores/collection1/ > [junit4] 2> 474578 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.c.CoresLocator Found 1 core definitions > [junit4] 2> 474579 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.s.SolrDispatchFilter > user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1 > [junit4] 2> 474579 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:56340_ ] o.a.s.s.SolrDispatchFilter > SolrDispatchFilter.init() done > [junit4] 2> 474579 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.ZkController > publishing state=down > [junit4] 2> 474579 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.ZkController > numShards not found on descriptor - reading it from system property > [junit4] 2> 474580 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.ZkController > Registering watch for collection collection1 > [junit4] 2> 474580 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader > addZkWatch collection1 > [junit4] 2> 474580 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] 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:56340";, > [junit4] 2> "node_name":"127.0.0.1:56340_", > [junit4] 2> "numShards":"2", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junit4] 2> "collection":"collection1", > [junit4] 2> "operation":"state"} current state version: 3 > [junit4] 2> 474580 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader > Add data for collection1 ver 0 > [junit4] 2> 474580 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.ZkController look > for our core node name > [junit4] 2> 474580 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 > message={ > [junit4] 2> "core":"collection1", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"http://127.0.0.1:56340";, > [junit4] 2> "node_name":"127.0.0.1:56340_", > [junit4] 2> "numShards":"2", > [junit4] 2> "state":"down", > [junit4] 2> "shard":null, > [junit4] 2> "collection":"collection1", > [junit4] 2> "operation":"state"} > [junit4] 2> 474580 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.o.ReplicaMutator Collection already exists > with numShards=2 > [junit4] 2> 474580 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard > shard=shard2 > [junit4] 2> 474682 INFO > (OverseerStateUpdate-94684562057068548-127.0.0.1:44193_-n_0000000000) > [n:127.0.0.1:44193_ ] o.a.s.c.o.ZkStateWriter going to update_collection > /collections/collection1/state.json version: 0 > [junit4] 2> 474682 INFO > (zkCallback-450-thread-1-processing-n:127.0.0.1:56340_) [n:127.0.0.1:56340_ > ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/collection1/state.json for collection collection1 has > occurred - updating... (live nodes size: 2) > [junit4] 2> 474682 INFO > (zkCallback-450-thread-1-processing-n:127.0.0.1:56340_) [n:127.0.0.1:56340_ > ] o.a.s.c.c.ZkStateReader Updating data for collection1 from 0 to 1 > [junit4] 2> 475580 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.ZkController > waiting to find shard id in clusterstate for collection1 > [junit4] 2> 475580 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.ZkController Check > for collection zkNode:collection1 > [junit4] 2> 475581 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.ZkController > Collection zkNode exists > [junit4] 2> 475581 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader > Load collection config from:/collections/collection1 > [junit4] 2> 475581 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader > path=/collections/collection1 configName=conf1 specified config exists in > ZooKeeper > [junit4] 2> 475581 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader > new SolrResourceLoader for directory: > '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-1-001/cores/collection1/' > [junit4] 2> 475581 INFO > (coreLoadExecutor-1583-thread-1-processing-n:127.0.0.1:56340_) > [n:127.0.0.1:56340_ c:collection1 x:collection1] o.a. > > [...truncated too long message...] > > s.c.CachingDirectoryFactory Closing directory: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001/shard-4-001/cores/collection1/data/index > [junit4] 2> 513578 INFO > (zkCallback-463-thread-1-processing-n:127.0.0.1:52355_) [n:127.0.0.1:52355_ > c:collection1 s:shard1 r:core_node4 x:collection1] > o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader > parent node, won't remove previous leader registration. > [junit4] 2> 513578 INFO > (zkCallback-463-thread-1-processing-n:127.0.0.1:52355_) [n:127.0.0.1:52355_ > c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.ElectionContext > Canceling election > /collections/collection1/leader_elect/shard1/election/94684562057068562-core_node4-n_0000000001 > [junit4] 2> 513578 WARN > (zkCallback-463-thread-1-processing-n:127.0.0.1:52355_) [n:127.0.0.1:52355_ > c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.LeaderElector > [junit4] 2> > org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode > = Session expired for > /collections/collection1/leader_elect/shard1/election/94684562057068562-core_node4-n_0000000001 > [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.delete(ZooKeeper.java:873) > [junit4] 2> at > org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:244) > [junit4] 2> at > org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:241) > [junit4] 2> at > org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61) > [junit4] 2> at > org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:241) > [junit4] 2> at > org.apache.solr.cloud.ElectionContext.cancelElection(ElectionContext.java:85) > [junit4] 2> at > org.apache.solr.cloud.ShardLeaderElectionContextBase.cancelElection(ElectionContext.java:166) > [junit4] 2> at > org.apache.solr.cloud.ShardLeaderElectionContext.cancelElection(ElectionContext.java:267) > [junit4] 2> at > org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:449) > [junit4] 2> at > org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:197) > [junit4] 2> at > org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:157) > [junit4] 2> at > org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:58) > [junit4] 2> at > org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:388) > [junit4] 2> at > org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:269) > [junit4] 2> at > java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) > [junit4] 2> at > java.util.concurrent.FutureTask.run(FutureTask.java:266) > [junit4] 2> at > org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) > [junit4] 2> at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > [junit4] 2> at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > [junit4] 2> at java.lang.Thread.run(Thread.java:745) > [junit4] 2> 513579 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.e.j.s.ServerConnector Stopped > ServerConnector@6be757b3{HTTP/1.1}{127.0.0.1:0} > [junit4] 2> 513579 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [ ] o.e.j.s.h.ContextHandler Stopped > o.e.j.s.ServletContextHandler@6d3c2203{/,null,UNAVAILABLE} > [junit4] 2> 513581 INFO > (TEST-SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection-seed#[1E4EBBB2B14DFD9C]) > [n:127.0.0.1:52355_ c:collection1 s:shard1 r:core_node4 x:collection1] > o.a.s.c.ZkTestServer connecting to 127.0.0.1:39969 39969 > [junit4] 2> 513606 INFO (Thread-1283) [ ] o.a.s.c.ZkTestServer > connecting to 127.0.0.1:39969 39969 > [junit4] 2> 513606 WARN (Thread-1283) [ ] o.a.s.c.ZkTestServer Watch > limit violations: > [junit4] 2> Maximum concurrent create/delete watches above limit: > [junit4] 2> > [junit4] 2> 9 /solr/aliases.json > [junit4] 2> 8 /solr/configs/gettingstarted/managed-schema > [junit4] 2> 8 /solr/configs/gettingstarted > [junit4] 2> 5 /solr/security.json > [junit4] 2> 5 /solr/configs/conf1 > [junit4] 2> > [junit4] 2> Maximum concurrent data watches above limit: > [junit4] 2> > [junit4] 2> 9 /solr/clusterstate.json > [junit4] 2> 4 /solr/collections/collection1/state.json > [junit4] 2> 4 /solr/collections/gettingstarted/state.json > [junit4] 2> > [junit4] 2> Maximum concurrent children watches above limit: > [junit4] 2> > [junit4] 2> 9 /solr/live_nodes > [junit4] 2> 9 /solr/collections > [junit4] 2> 4 /solr/overseer/queue > [junit4] 2> 4 /solr/overseer/collection-queue-work > [junit4] 2> > [junit4] 2> NOTE: reproduce with: ant test > -Dtestcase=SolrCloudExampleTest > -Dtests.method=testLoadDocsIntoGettingStartedCollection > -Dtests.seed=1E4EBBB2B14DFD9C -Dtests.multiplier=3 -Dtests.slow=true > -Dtests.locale=pl -Dtests.timezone=Europe/Athens -Dtests.asserts=true > -Dtests.file.encoding=ISO-8859-1 > [junit4] FAILURE 41.9s J1 | > SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection <<< > [junit4] > Throwable #1: java.lang.AssertionError: Delete action failed! > [junit4] > at > __randomizedtesting.SeedInfo.seed([1E4EBBB2B14DFD9C:D2D89DD8022443A]:0) > [junit4] > at > org.apache.solr.cloud.SolrCloudExampleTest.doTestDeleteAction(SolrCloudExampleTest.java:169) > [junit4] > at > org.apache.solr.cloud.SolrCloudExampleTest.testLoadDocsIntoGettingStartedCollection(SolrCloudExampleTest.java:145) > [junit4] > at > org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) > [junit4] > at > org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) > [junit4] > at java.lang.Thread.run(Thread.java:745) > [junit4] 2> 513610 INFO > (SUITE-SolrCloudExampleTest-seed#[1E4EBBB2B14DFD9C]-worker) > [n:127.0.0.1:52355_ c:collection1 s:shard1 r:core_node4 x:collection1] > 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.SolrCloudExampleTest_1E4EBBB2B14DFD9C-001 > [junit4] 2> Oct 13, 2015 9:30:01 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): {}, > docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, > locale=pl, timezone=Europe/Athens > [junit4] 2> NOTE: Linux 3.19.0-30-generic amd64/Oracle Corporation > 1.8.0_60 (64-bit)/cpus=12,threads=1,free=132459464,total=527958016 > [junit4] 2> NOTE: All tests run in this JVM: [TestQueryWrapperFilter, > TestStressUserVersions, TestFiltering, TestConfigSetsAPI, TestCoreContainer, > SolrIndexSplitterTest, PreAnalyzedUpdateProcessorTest, > TestSolrDeletionPolicy2, CustomCollectionTest, TestBlendedInfixSuggestions, > TestUpdate, TestCopyFieldCollectionResource, RollingRestartTest, > DistributedQueueTest, TestJmxMonitoredMap, TestNonDefinedSimilarityFactory, > HighlighterTest, SchemaVersionSpecificBehaviorTest, MinimalSchemaTest, > SaslZkACLProviderTest, SimpleMLTQParserTest, > DistributedFacetPivotLongTailTest, ConvertedLegacyTest, > TestSchemaVersionResource, TestLeaderElectionZkExpiry, TestMacros, > FastVectorHighlighterTest, InfoHandlerTest, CollectionReloadTest, > ChaosMonkeySafeLeaderTest, ActionThrottleTest, ShardRoutingTest, > DistanceUnitsTest, DocumentBuilderTest, SortByFunctionTest, > SharedFSAutoReplicaFailoverTest, TestCrossCoreJoin, ClusterStateUpdateTest, > LeaderFailoverAfterPartitionTest, ShardRoutingCustomTest, RuleEngineTest, > DistanceFunctionTest, AtomicUpdatesTest, SliceStateTest, > CoreAdminRequestStatusTest, DOMUtilTest, TestDFRSimilarityFactory, > TestFreeTextSuggestions, ConnectionManagerTest, TestDownShardTolerantSearch, > TestSolrQueryParserDefaultOperatorResource, TestComplexPhraseQParserPlugin, > SuggestComponentTest, TestCloudInspectUtil, TestJettySolrRunner, > SolrCloudExampleTest] > [junit4] Completed [178/552] on J1 in 42.13s, 1 test, 1 failure <<< > FAILURES! > > [...truncated 1170 lines...] > BUILD FAILED > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:775: The following > error occurred while executing this line: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:719: 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:516: > The following error occurred while executing this line: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1432: > The following error occurred while executing this line: > /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:987: > There were test failures: 552 suites (8 ignored), 2170 tests, 1 failure, 74 > ignored (37 assumptions) [seed: 1E4EBBB2B14DFD9C] > > Total time: 47 minutes 47 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] --------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
