Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4328/ Java: 32bit/jdk1.7.0_67 -client -XX:+UseConcMarkSweepGC
2 tests failed. REGRESSION: org.apache.solr.cloud.BasicDistributedZkTest.testDistribSearch Error Message: commitWithin did not work on node: http://127.0.0.1:56685/collection1 expected:<68> but was:<67> Stack Trace: java.lang.AssertionError: commitWithin did not work on node: http://127.0.0.1:56685/collection1 expected:<68> but was:<67> at __randomizedtesting.SeedInfo.seed([72BE16B7D149BA03:F35898AFA616DA3F]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:345) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) 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:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) 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:43) 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:365) at java.lang.Thread.run(Thread.java:745) REGRESSION: org.apache.solr.cloud.DeleteReplicaTest.testDistribSearch Error Message: No live SolrServers available to handle this request:[http://127.0.0.1:56327/co, http://127.0.0.1:56300/co, http://127.0.0.1:56318/co, http://127.0.0.1:56285/co, http://127.0.0.1:56309/co] Stack Trace: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:56327/co, http://127.0.0.1:56300/co, http://127.0.0.1:56318/co, http://127.0.0.1:56285/co, http://127.0.0.1:56309/co] at __randomizedtesting.SeedInfo.seed([72BE16B7D149BA03:F35898AFA616DA3F]:0) at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:322) at org.apache.solr.client.solrj.impl.CloudSolrServer.sendRequest(CloudSolrServer.java:874) at org.apache.solr.client.solrj.impl.CloudSolrServer.requestWithRetryOnStaleState(CloudSolrServer.java:658) at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:601) at org.apache.solr.cloud.DeleteReplicaTest.removeAndWaitForReplicaGone(DeleteReplicaTest.java:171) at org.apache.solr.cloud.DeleteReplicaTest.deleteLiveReplicaTest(DeleteReplicaTest.java:144) at org.apache.solr.cloud.DeleteReplicaTest.doTest(DeleteReplicaTest.java:88) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) 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:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) 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:43) 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:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Could not remove replica : delLiveColl/shard1/core_node1 at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:550) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206) at org.apache.solr.client.solrj.impl.LBHttpSolrServer.doRequest(LBHttpSolrServer.java:343) at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:304) ... 47 more Build Log: [...truncated 10738 lines...] [junit4] Suite: org.apache.solr.cloud.DeleteReplicaTest [junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\init-core-data-001 [junit4] 2> 829021 T1567 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 829022 T1567 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /co/ [junit4] 2> 829026 T1567 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 829027 T1567 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 829028 T1568 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 829112 T1567 oasc.ZkTestServer.run start zk server on port:56278 [junit4] 2> 829121 T1567 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 829122 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 829126 T1574 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e04c00 name:ZooKeeperConnection Watcher:127.0.0.1:56278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 829127 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 829127 T1567 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 829127 T1567 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 829132 T1567 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 829132 T1569 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x148955b3e460000, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 829136 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 829137 T1576 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1939ae5 name:ZooKeeperConnection Watcher:127.0.0.1:56278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 829138 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 829138 T1567 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 829138 T1567 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 829144 T1567 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 829149 T1567 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 829153 T1567 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 829158 T1567 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 829158 T1567 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 829167 T1567 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml [junit4] 2> 829167 T1567 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 829175 T1567 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 829175 T1567 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 829181 T1567 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 829181 T1567 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 829187 T1567 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 829188 T1567 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 829193 T1567 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4] 2> 829193 T1567 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 829200 T1567 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 829201 T1567 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 829205 T1567 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 829207 T1567 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 829211 T1567 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 829211 T1567 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 829217 T1567 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 829217 T1567 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 829222 T1567 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 829222 T1567 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 830892 T1567 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 830900 T1567 oejs.AbstractConnector.doStart Started [email protected]:56285 [junit4] 2> 830900 T1567 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 830900 T1567 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 830900 T1567 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-002 [junit4] 2> 830901 T1567 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-002\' [junit4] 2> 830955 T1567 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-002\solr.xml [junit4] 2> 831029 T1567 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-002 [junit4] 2> 831029 T1567 oasc.CoreContainer.<init> New CoreContainer 2902006 [junit4] 2> 831029 T1567 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-002\] [junit4] 2> 831032 T1567 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 831032 T1567 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 831032 T1567 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 831032 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 831032 T1567 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 831032 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 831032 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 831032 T1567 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 831032 T1567 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 831032 T1567 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 831034 T1567 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 831034 T1567 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 831034 T1567 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 831034 T1567 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56278/solr [junit4] 2> 831034 T1567 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 831036 T1567 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 831038 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 831040 T1587 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@128a310 name:ZooKeeperConnection Watcher:127.0.0.1:56278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 831041 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 831041 T1567 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 831048 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 831052 T1589 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b63fb7 name:ZooKeeperConnection Watcher:127.0.0.1:56278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 831052 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 831054 T1567 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 831060 T1567 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 831065 T1567 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 831071 T1567 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 831088 T1567 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 831097 T1567 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 831102 T1567 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56285_co [junit4] 2> 831104 T1567 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56285_co [junit4] 2> 831109 T1567 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 831112 T1567 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 831116 T1567 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 831118 T1567 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:56285_co [junit4] 2> 831119 T1567 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 831122 T1567 oasc.Overseer.start Overseer (id=92488011480432643-127.0.0.1:56285_co-n_0000000000) starting [junit4] 2> 831127 T1567 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 831145 T1567 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 831146 T1591 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 831150 T1567 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 831155 T1567 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 831158 T1567 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 831166 T1590 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 831171 T1567 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-002 [junit4] 2> 832321 T1567 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-002\conf\ [junit4] 2> 832529 T1567 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 832532 T1567 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 832532 T1567 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 832533 T1567 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 832535 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 832537 T1595 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1cacaee name:ZooKeeperConnection Watcher:127.0.0.1:56278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 832537 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 832537 T1567 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 832541 T1567 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 832548 T1567 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 832549 T1567 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 832549 T1567 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 832551 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 832553 T1597 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c8618 name:ZooKeeperConnection Watcher:127.0.0.1:56278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 832553 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 832553 T1567 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 832559 T1569 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x148955b3e460005, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 832560 T1598 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 832568 T1590 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: collection1 [junit4] 2> 832568 T1590 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2] [junit4] 2> 832568 T1590 oasc.Overseer$ClusterStateUpdater.createCollection state version collection1 2 [junit4] 2> 832574 T1590 oasc.Overseer$ClusterStateUpdater.updateZkStates going to create_collection /collections/collection1/state.json [junit4] 2> 832579 T1598 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 832581 T1599 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 834414 T1567 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 834415 T1567 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 834420 T1567 oejs.AbstractConnector.doStart Started [email protected]:56300 [junit4] 2> 834421 T1567 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 834421 T1567 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 834421 T1567 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-003 [junit4] 2> 834421 T1567 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-003\' [junit4] 2> 834472 T1567 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-003\solr.xml [junit4] 2> 834540 T1567 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-003 [junit4] 2> 834540 T1567 oasc.CoreContainer.<init> New CoreContainer 4721873 [junit4] 2> 834540 T1567 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-003\] [junit4] 2> 834542 T1567 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 834542 T1567 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 834542 T1567 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 834542 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 834542 T1567 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 834542 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 834542 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 834542 T1567 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 834543 T1567 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 834546 T1567 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 834547 T1567 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 834547 T1567 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 834549 T1567 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 834549 T1567 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56278/solr [junit4] 2> 834549 T1567 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 834551 T1567 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 834561 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 834564 T1610 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@417ff2 name:ZooKeeperConnection Watcher:127.0.0.1:56278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 834565 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 834565 T1567 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 834569 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 834571 T1612 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1019ae7 name:ZooKeeperConnection Watcher:127.0.0.1:56278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 834572 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 834586 T1567 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 835522 T1567 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56300_co [junit4] 2> 835526 T1567 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56300_co [junit4] 2> 835542 T1567 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 835549 T1567 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-003 [junit4] 2> 836187 T1567 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-003\conf\ [junit4] 2> 836313 T1567 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 836315 T1567 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 836315 T1567 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 838026 T1567 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 838027 T1567 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 838031 T1567 oejs.AbstractConnector.doStart Started [email protected]:56309 [junit4] 2> 838032 T1567 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 838032 T1567 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 838032 T1567 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-004 [junit4] 2> 838032 T1567 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-004\' [junit4] 2> 838092 T1567 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-004\solr.xml [junit4] 2> 838171 T1567 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-004 [junit4] 2> 838172 T1567 oasc.CoreContainer.<init> New CoreContainer 19135614 [junit4] 2> 838173 T1567 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-004\] [junit4] 2> 838176 T1567 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 838177 T1567 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 838177 T1567 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 838178 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 838179 T1567 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 838180 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 838180 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 838181 T1567 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 838182 T1567 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 838183 T1567 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 838183 T1567 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 838183 T1567 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 838184 T1567 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 838184 T1567 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56278/solr [junit4] 2> 838185 T1567 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 838185 T1567 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 838187 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 838190 T1625 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@195f461 name:ZooKeeperConnection Watcher:127.0.0.1:56278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 838191 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 838191 T1567 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 838196 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 838199 T1627 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a98d7 name:ZooKeeperConnection Watcher:127.0.0.1:56278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 838199 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 838212 T1567 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 839226 T1567 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56309_co [junit4] 2> 839229 T1567 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56309_co [junit4] 2> 839245 T1567 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 839256 T1567 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-004 [junit4] 2> 840541 T1567 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-004\conf\ [junit4] 2> 840766 T1567 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 840770 T1567 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 840770 T1567 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 842714 T1567 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3 [junit4] 2> 842714 T1567 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 842719 T1567 oejs.AbstractConnector.doStart Started [email protected]:56318 [junit4] 2> 842720 T1567 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 842720 T1567 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 842720 T1567 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005 [junit4] 2> 842720 T1567 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005\' [junit4] 2> 842775 T1567 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005\solr.xml [junit4] 2> 842859 T1567 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005 [junit4] 2> 842859 T1567 oasc.CoreContainer.<init> New CoreContainer 5584711 [junit4] 2> 842859 T1567 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005\] [junit4] 2> 842861 T1567 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 842861 T1567 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 842861 T1567 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 842861 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 842868 T1567 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 842868 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 842868 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 842868 T1567 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 842868 T1567 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 842871 T1567 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 842871 T1567 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 842871 T1567 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 842871 T1567 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 842871 T1567 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56278/solr [junit4] 2> 842871 T1567 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 842873 T1567 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 842876 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 842878 T1640 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@74ab79 name:ZooKeeperConnection Watcher:127.0.0.1:56278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 842878 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 842878 T1567 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 842884 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 842886 T1642 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bed869 name:ZooKeeperConnection Watcher:127.0.0.1:56278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 842886 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 842900 T1567 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 843928 T1567 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56318_co [junit4] 2> 843932 T1567 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56318_co [junit4] 2> 843949 T1567 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 843957 T1567 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005 [junit4] 2> 845258 T1567 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005\conf\ [junit4] 2> 845475 T1567 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 845479 T1567 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 845479 T1567 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 847311 T1567 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4 [junit4] 2> 847311 T1567 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 847315 T1567 oejs.AbstractConnector.doStart Started [email protected]:56327 [junit4] 2> 847316 T1567 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 847316 T1567 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 847316 T1567 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-006 [junit4] 2> 847316 T1567 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-006\' [junit4] 2> 847366 T1567 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-006\solr.xml [junit4] 2> 847459 T1567 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-006 [junit4] 2> 847460 T1567 oasc.CoreContainer.<init> New CoreContainer 13292125 [junit4] 2> 847460 T1567 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-006\] [junit4] 2> 847462 T1567 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 847463 T1567 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 847463 T1567 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 847464 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 847464 T1567 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 847464 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 847465 T1567 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 847465 T1567 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 847466 T1567 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 847466 T1567 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 847468 T1567 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 847468 T1567 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 847469 T1567 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 847469 T1567 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56278/solr [junit4] 2> 847469 T1567 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 847470 T1567 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 847474 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 847477 T1655 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b8fbfa name:ZooKeeperConnection Watcher:127.0.0.1:56278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 847479 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 847479 T1567 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 847484 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 847487 T1657 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1784300 name:ZooKeeperConnection Watcher:127.0.0.1:56278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 847487 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 847507 T1567 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 848540 T1567 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56327_co [junit4] 2> 848542 T1567 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56327_co [junit4] 2> 848560 T1567 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 848576 T1567 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-006 [junit4] 2> 849410 T1567 oasc.CorePropertiesLocator.discoverUnder Found core conf in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-006\conf\ [junit4] 2> 849568 T1567 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 849572 T1567 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 849573 T1567 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 849580 T1567 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 849588 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 849588 T1661 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13ca6e5 name:ZooKeeperConnection Watcher:127.0.0.1:56278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 849590 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 849590 T1567 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 849593 T1567 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 849604 T1567 oasc.AbstractFullDistribZkTestBase.createCollection Creating collection with stateFormat=2: delLiveColl [junit4] 2> 849605 T1567 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 849608 T1567 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 849623 T1663 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@90e9da name:ZooKeeperConnection Watcher:127.0.0.1:56278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 849624 T1567 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 849624 T1567 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 849628 T1567 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 849643 T1637 oasha.CollectionsHandler.handleCreateAction Creating Collection : name=delLiveColl&numShards=2&wt=javabin&stateFormat=2&version=2&replicationFactor=2&action=CREATE&maxShardsPerNode=2 [junit4] 2> 849645 T1598 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged [junit4] 2> 849649 T1591 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{ [junit4] 2> "operation":"create", [junit4] 2> "fromApi":"true", [junit4] 2> "name":"delLiveColl", [junit4] 2> "replicationFactor":"2", [junit4] 2> "numShards":"2", [junit4] 2> "maxShardsPerNode":"2", [junit4] 2> "stateFormat":"2"} [junit4] 2> 849652 T1664 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : create , { [junit4] 2> "operation":"create", [junit4] 2> "fromApi":"true", [junit4] 2> "name":"delLiveColl", [junit4] 2> "replicationFactor":"2", [junit4] 2> "numShards":"2", [junit4] 2> "maxShardsPerNode":"2", [junit4] 2> "stateFormat":"2"} [junit4] 2> 849656 T1664 oasc.OverseerCollectionProcessor.createConfNode Only one config set found in zk - using it:conf1 [junit4] 2> 849656 T1664 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/delLiveColl [junit4] 2> 849660 T1664 oascc.SolrZkClient.makePath makePath: /collections/delLiveColl [junit4] 2> 849669 T1598 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 849678 T1590 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: delLiveColl [junit4] 2> 849679 T1590 oasc.Overseer$ClusterStateUpdater.createCollection Create collection delLiveColl with shards [shard1, shard2] [junit4] 2> 849679 T1590 oasc.Overseer$ClusterStateUpdater.createCollection state version delLiveColl 2 [junit4] 2> 849685 T1590 oasc.Overseer$ClusterStateUpdater.updateZkStates going to create_collection /collections/delLiveColl/state.json [junit4] 2> 849689 T1665 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 849690 T1658 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 849691 T1666 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 849693 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 849693 T1598 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 849693 T1599 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 849696 T1643 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 849696 T1628 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5) [junit4] 2> 849782 T1664 oasc.OverseerCollectionProcessor.createCollection Creating SolrCores for new collection delLiveColl, shardNames [shard1, shard2] , replicationFactor : 2 [junit4] 2> 849782 T1664 oasc.OverseerCollectionProcessor.createCollection Creating shard delLiveColl_shard1_replica1 as part of slice shard1 of collection delLiveColl on 127.0.0.1:56300_co [junit4] 2> 849784 T1664 oasc.OverseerCollectionProcessor.createCollection Creating shard delLiveColl_shard1_replica2 as part of slice shard1 of collection delLiveColl on 127.0.0.1:56285_co [junit4] 2> 849787 T1664 oasc.OverseerCollectionProcessor.createCollection Creating shard delLiveColl_shard2_replica1 as part of slice shard2 of collection delLiveColl on 127.0.0.1:56318_co [junit4] 2> 849789 T1605 oasha.CoreAdminHandler.handleCreateAction core create command name=delLiveColl_shard1_replica1&numShards=2&wt=javabin&qt=/admin/cores&version=2&action=CREATE&shard=shard1&collection.configName=conf1&collection=delLiveColl [junit4] 2> 849794 T1664 oasc.OverseerCollectionProcessor.createCollection Creating shard delLiveColl_shard2_replica2 as part of slice shard2 of collection delLiveColl on 127.0.0.1:56309_co [junit4] 2> 849797 T1581 oasha.CoreAdminHandler.handleCreateAction core create command name=delLiveColl_shard1_replica2&numShards=2&wt=javabin&qt=/admin/cores&version=2&action=CREATE&shard=shard1&collection.configName=conf1&collection=delLiveColl [junit4] 2> 849799 T1638 oasha.CoreAdminHandler.handleCreateAction core create command name=delLiveColl_shard2_replica1&numShards=2&wt=javabin&qt=/admin/cores&version=2&action=CREATE&shard=shard2&collection.configName=conf1&collection=delLiveColl [junit4] 2> 849812 T1621 oasha.CoreAdminHandler.handleCreateAction core create command name=delLiveColl_shard2_replica2&numShards=2&wt=javabin&qt=/admin/cores&version=2&action=CREATE&shard=shard2&collection.configName=conf1&collection=delLiveColl [junit4] 2> 849813 T1605 oasc.ZkController.publish publishing core=delLiveColl_shard1_replica1 state=down collection=delLiveColl [junit4] 2> 849813 T1581 oasc.ZkController.publish publishing core=delLiveColl_shard1_replica2 state=down collection=delLiveColl [junit4] 2> 849817 T1638 oasc.ZkController.publish publishing core=delLiveColl_shard2_replica1 state=down collection=delLiveColl [junit4] 2> 849822 T1598 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 849825 T1581 oasc.ZkController.preRegister Registering watch for external collection delLiveColl [junit4] 2> 849826 T1581 oascc.ZkStateReader.addZkWatch addZkWatch delLiveColl [junit4] 2> 849829 T1621 oasc.ZkController.publish publishing core=delLiveColl_shard2_replica2 state=down collection=delLiveColl [junit4] 2> 849831 T1581 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/delLiveColl/state.json from ZooKeeper... [junit4] 2> 849832 T1638 oasc.ZkController.preRegister Registering watch for external collection delLiveColl [junit4] 2> 849833 T1638 oascc.ZkStateReader.addZkWatch addZkWatch delLiveColl [junit4] 2> 849832 T1605 oasc.ZkController.preRegister Registering watch for external collection delLiveColl [junit4] 2> 849835 T1605 oascc.ZkStateReader.addZkWatch addZkWatch delLiveColl [junit4] 2> 849836 T1638 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/delLiveColl/state.json from ZooKeeper... [junit4] 2> 849836 T1581 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 0 [junit4] 2> 849837 T1581 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 849840 T1605 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/delLiveColl/state.json from ZooKeeper... [junit4] 2> 849843 T1638 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 0 [junit4] 2> 849843 T1638 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 849844 T1621 oasc.ZkController.preRegister Registering watch for external collection delLiveColl [junit4] 2> 849845 T1621 oascc.ZkStateReader.addZkWatch addZkWatch delLiveColl [junit4] 2> 849858 T1621 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/delLiveColl/state.json from ZooKeeper... [junit4] 2> 849859 T1605 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 0 [junit4] 2> 849860 T1605 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 849865 T1621 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 0 [junit4] 2> 849866 T1621 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 849866 T1590 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 0 [junit4] 2> 849867 T1590 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "node_name":"127.0.0.1:56285_co", [junit4] 2> "core":"delLiveColl_shard1_replica2", [junit4] 2> "numShards":"2", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:56285/co";, [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"delLiveColl"} [junit4] 2> 849878 T1590 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/delLiveColl/state.json [junit4] 2> 849880 T1643 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849881 T1598 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849881 T1628 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849882 T1613 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849884 T1628 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 1 [junit4] 2> 849884 T1643 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 1 [junit4] 2> 849886 T1613 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 1 [junit4] 2> 849891 T1598 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 1 [junit4] 2> 849902 T1590 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 1 [junit4] 2> 849903 T1590 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "node_name":"127.0.0.1:56300_co", [junit4] 2> "core":"delLiveColl_shard1_replica1", [junit4] 2> "numShards":"2", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:56300/co";, [junit4] 2> "state":"down", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"delLiveColl"} [junit4] 2> 849908 T1598 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 849909 T1590 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/delLiveColl/state.json [junit4] 2> 849913 T1598 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849913 T1613 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849914 T1628 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849915 T1643 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849916 T1613 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 2 [junit4] 2> 849917 T1628 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 2 [junit4] 2> 849918 T1643 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 2 [junit4] 2> 849922 T1598 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 2 [junit4] 2> 849932 T1590 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 2 [junit4] 2> 849933 T1590 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "node_name":"127.0.0.1:56318_co", [junit4] 2> "core":"delLiveColl_shard2_replica1", [junit4] 2> "numShards":"2", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:56318/co";, [junit4] 2> "state":"down", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"delLiveColl"} [junit4] 2> 849940 T1598 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 849941 T1590 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/delLiveColl/state.json [junit4] 2> 849942 T1643 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849943 T1598 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849944 T1613 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849944 T1628 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849945 T1643 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 3 [junit4] 2> 849947 T1613 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 3 [junit4] 2> 849947 T1628 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 3 [junit4] 2> 849953 T1598 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 3 [junit4] 2> 849961 T1590 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 3 [junit4] 2> 849962 T1590 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={ [junit4] 2> "node_name":"127.0.0.1:56309_co", [junit4] 2> "core":"delLiveColl_shard2_replica2", [junit4] 2> "numShards":"2", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:56309/co";, [junit4] 2> "state":"down", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"delLiveColl"} [junit4] 2> 849966 T1598 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged [junit4] 2> 849967 T1590 oasc.Overseer$ClusterStateUpdater.updateZkStates going to update_collection /collections/delLiveColl/state.json [junit4] 2> 849969 T1643 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849970 T1598 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849970 T1613 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849970 T1628 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/delLiveColl/state.json, has occurred - updating... [junit4] 2> 849973 T1643 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 4 [junit4] 2> 849974 T1628 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 4 [junit4] 2> 849974 T1613 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 4 [junit4] 2> 849979 T1598 oascc.ZkStateReader.updateWatchedCollection Updating data for delLiveColl to ver 4 [junit4] 2> 850954 T1581 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delLiveColl_shard1_replica2 [junit4] 2> 850955 T1581 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delLiveColl [junit4] 2> 850957 T1581 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 850957 T1581 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delLiveColl [junit4] 2> 850960 T1581 oascc.ZkStateReader.readConfigName path=/collections/delLiveColl configName=conf1 specified config exists in ZooKeeper [junit4] 2> 850961 T1581 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-002\delLiveColl_shard1_replica2\' [junit4] 2> 850960 T1638 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delLiveColl_shard2_replica1 [junit4] 2> 850976 T1638 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delLiveColl [junit4] 2> 850980 T1638 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 850980 T1638 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delLiveColl [junit4] 2> 850985 T1638 oascc.ZkStateReader.readConfigName path=/collections/delLiveColl configName=conf1 specified config exists in ZooKeeper [junit4] 2> 850985 T1638 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005\delLiveColl_shard2_replica1\' [junit4] 2> 850992 T1605 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delLiveColl_shard1_replica1 [junit4] 2> 851004 T1605 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delLiveColl [junit4] 2> 851002 T1621 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for delLiveColl_shard2_replica2 [junit4] 2> 851007 T1621 oasc.ZkController.createCollectionZkNode Check for collection zkNode:delLiveColl [junit4] 2> 851016 T1605 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 851016 T1605 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delLiveColl [junit4] 2> 851018 T1621 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 851018 T1621 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delLiveColl [junit4] 2> 851026 T1621 oascc.ZkStateReader.readConfigName path=/collections/delLiveColl configName=conf1 specified config exists in ZooKeeper [junit4] 2> 851026 T1621 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-004\delLiveColl_shard2_replica2\' [junit4] 2> 851031 T1605 oascc.ZkStateReader.readConfigName path=/collections/delLiveColl configName=conf1 specified config exists in ZooKeeper [junit4] 2> 851032 T1605 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-003\delLiveColl_shard1_replica1\' [junit4] 2> 851371 T1638 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 851438 T1581 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 851441 T1621 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 851599 T1605 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 851716 T1638 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 851810 T1638 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 851835 T1638 oass.IndexSchema.readSchema [delLiveColl_shard2_replica1] Schema name=test [junit4] 2> 851854 T1605 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 851887 T1605 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 851909 T1605 oass.IndexSchema.readSchema [delLiveColl_shard1_replica1] Schema name=test [junit4] 2> 852043 T1621 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 852089 T1581 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 852159 T1581 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 852201 T1621 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml [junit4] 2> 852208 T1581 oass.IndexSchema.readSchema [delLiveColl_shard1_replica2] Schema name=test [junit4] 2> 852244 T1621 oass.IndexSchema.readSchema [delLiveColl_shard2_replica2] Schema name=test [junit4] 2> 853842 T1638 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 853883 T1638 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 853887 T1638 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 854441 T1605 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 854485 T1605 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 854490 T1605 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 854679 T1638 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 854715 T1638 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 854733 T1581 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 854749 T1581 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 854762 T1581 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 854772 T1638 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 854791 T1638 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 854795 T1638 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 854795 T1638 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 854799 T1638 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 854800 T1638 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 854800 T1638 oasc.CoreContainer.create Creating SolrCore 'delLiveColl_shard2_replica1' using configuration from collection delLiveColl [junit4] 2> 854800 T1638 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 854801 T1638 oasc.SolrCore.<init> [delLiveColl_shard2_replica1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005\delLiveColl_shard2_replica1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005\delLiveColl_shard2_replica1\data\ [junit4] 2> 854801 T1638 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@121b417 [junit4] 2> 854803 T1638 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005\delLiveColl_shard2_replica1\data\ [junit4] 2> 854803 T1638 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005\delLiveColl_shard2_replica1\data\index/ [junit4] 2> 854805 T1638 oasc.SolrCore.initIndex WARN [delLiveColl_shard2_replica1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005\delLiveColl_shard2_replica1\data\index' doesn't exist. Creating new index... [junit4] 2> 854813 T1638 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.DeleteReplicaTest-72BE16B7D149BA03-001\tempDir-005\delLiveColl_shard2_replica1\data\index [junit4] 2> 854815 T1638 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 854816 T1638 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-847a54)),segFN=segments_1,generation=1} [junit4] 2> 854816 T1638 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 854825 T1638 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 854827 T1638 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 854827 T1638 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 854828 T1638 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 854828 T1638 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 854828 T1638 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 854829 T1638 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 854829 T1638 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 854829 T1638 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 854837 T1638 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 854840 T1638 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 854840 T1638 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 854841 T1638 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 854841 T1638 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler [junit4] 2> 854841 T1638 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler [junit4] 2> 854843 T1638 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler [junit4] 2> 854843 T1638 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler [junit4] 2> 854844 T1638 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers [junit4] 2> 854844 T1638 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 854845 T1638 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 854867 T1638 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 854870 T1638 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 854873 T1638 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 854884 T1638 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 854892 T1638 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 854892 T1638 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 854894 T1638 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=17.4951171875, floorSegmentMB=1.525390625, forceMergeDeletesPctAllowed=14.747622194286354, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 854895 T1638 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-847a54)),segFN=segments_1,generation=1} [junit4] 2> 854895 T1638 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 854896 T1638 oass.SolrIndexSearcher.<init> Opening Searcher@1f9a888[delLiveColl_shard2_replica1] main [junit4] 2> 854896 T1638 oascc.ZkStateReader.readConfigName Load collection config from:/collections/delLiveColl [junit4] 2> 854914 T1638 oascc.ZkStateReader.readConfigName path=/collections/delLiveColl configName=conf1 specified config exists in ZooKeeper [junit4] 2> 854915 T1638 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 854918 T1638 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 854918 T1638 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 854918 T1621 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 854918 T1638 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 854920 T1605 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 854924 T1638 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 854933 T1638 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 854939 T1638 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 854940 T1621 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 854943 T1621 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 854943 T1605 oass.FileExch [...truncated too long message...] d4LetterWord connecting to 127.0.0.1:56645 56645 [junit4] 2> 1319859 T2365 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:56645 56645 [junit4] 2> 1319861 T2365 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 7 /solr/clusterstate.json [junit4] 2> 7 /solr/aliases.json [junit4] 2> 4 /solr/collections/collection1/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 7 /solr/live_nodes [junit4] 2> 5 /solr/overseer/collection-queue-work [junit4] 2> 3 /solr/overseer/queue [junit4] 2> [junit4] 2> 1319936 T2508 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b8855 name:ZooKeeperConnection Watcher:127.0.0.1:56645/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None [junit4] 2> 1319936 T2508 oascc.ConnectionManager.process Client->ZooKeeper status change trigger but we are already closed [junit4] 2> 1319937 T2508 oaz.ClientCnxn$EventThread.processEvent ERROR Error while calling watcher java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@11665bf rejected from java.util.concurrent.ThreadPoolExecutor@171f7c6[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 6] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2048) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:821) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1372) [junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:110) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:263) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498) [junit4] 2> [junit4] 2> 1319949 T2508 oaz.ClientCnxn$EventThread.processEvent ERROR Error while calling watcher java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@10039c6 rejected from java.util.concurrent.ThreadPoolExecutor@171f7c6[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 6] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2048) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:821) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1372) [junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:110) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:263) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498) [junit4] 2> [junit4] 2> 1319952 T2508 oaz.ClientCnxn$EventThread.processEvent ERROR Error while calling watcher java.util.concurrent.RejectedExecutionException: Task java.util.concurrent.FutureTask@196d0ff rejected from java.util.concurrent.ThreadPoolExecutor@171f7c6[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 6] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2048) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:821) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1372) [junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:110) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:263) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498) [junit4] 2> [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicDistributedZkTest -Dtests.method=testDistribSearch -Dtests.seed=72BE16B7D149BA03 -Dtests.slow=true -Dtests.locale=ar_EG -Dtests.timezone=Australia/Broken_Hill -Dtests.file.encoding=Cp1252 [junit4] FAILURE 71.8s | BasicDistributedZkTest.testDistribSearch <<< [junit4] > Throwable #1: java.lang.AssertionError: commitWithin did not work on node: http://127.0.0.1:56685/collection1 expected:<68> but was:<67> [junit4] > at __randomizedtesting.SeedInfo.seed([72BE16B7D149BA03:F35898AFA616DA3F]:0) [junit4] > at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:345) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:869) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1319955 T2364 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.BasicDistributedZkTest-72BE16B7D149BA03-001 [junit4] 2> 71775 T2363 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene410: {a_i1=FSTOrd41, n_f1=PostingsFormat(name=Asserting), id=PostingsFormat(name=SimpleText), other_tl1=PostingsFormat(name=Asserting), n_ti1=PostingsFormat(name=SimpleText), oddField_s=PostingsFormat(name=SimpleText), timestamp=FSTOrd41, foo_f=PostingsFormat(name=Memory doPackFST= false), n_d1=FSTOrd41, n_dt1=PostingsFormat(name=Asserting), multiDefault=FSTOrd41, n_tl1=PostingsFormat(name=Asserting), range_facet_l=FSTOrd41, n_l1=FSTOrd41, _version_=PostingsFormat(name=Asserting), foo_b=PostingsFormat(name=Memory doPackFST= false), rnd_b=PostingsFormat(name=SimpleText), foo_d=PostingsFormat(name=SimpleText), SubjectTerms_mfacet=PostingsFormat(name=Asserting), text=PostingsFormat(name=Memory doPackFST= false), intDefault=PostingsFormat(name=Asserting), n_tdt1=PostingsFormat(name=Asserting), n_tf1=FSTOrd41, a_t=FSTOrd41, foo_i=PostingsFormat(name=Asserting), n_td1=PostingsFormat(name=Asserting)}, docValues:{timestamp=DocValuesFormat(name=Asserting)}, sim=DefaultSimilarity, locale=ar_EG, timezone=Australia/Broken_Hill [junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_67 (32-bit)/cpus=2,threads=1,free=82690864,total=247947264 [junit4] 2> NOTE: All tests run in this JVM: [OutputWriterTest, TestSweetSpotSimilarityFactory, QueryResultKeyTest, BadIndexSchemaTest, DateRangeFieldTest, ChaosMonkeySafeLeaderTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestXIncludeConfig, BasicZkTest, ResourceLoaderTest, DefaultValueUpdateProcessorTest, OverseerStatusTest, SystemInfoHandlerTest, DistributedTermsComponentTest, TestManagedSynonymFilterFactory, DateFieldTest, TestCustomSort, TestCloudPivotFacet, TestMissingGroups, TestSerializedLuceneMatchVersion, XmlUpdateRequestHandlerTest, TestLuceneMatchVersion, SolrInfoMBeanTest, ShowFileRequestHandlerTest, TestSolrDeletionPolicy1, TestCoreDiscovery, SimplePostToolTest, TestManagedSchemaFieldTypeResource, TestShardHandlerFactory, DirectUpdateHandlerTest, TestCloudInspectUtil, OverseerRolesTest, PluginInfoTest, TestSort, LeaderElectionTest, CSVRequestHandlerTest, DebugComponentTest, TestBadConfig, ConnectionManagerTest, TestManagedResourceStorage, TestIntervalFaceting, TestPostingsSolrHighlighter, ShardSplitTest, TestDocSet, TestFastWriter, DeleteReplicaTest, DocValuesTest, NumericFieldsTest, TestManagedStopFilterFactory, TestSearcherReuse, DistributedExpandComponentTest, DirectSolrSpellCheckerTest, CoreAdminHandlerTest, ScriptEngineTest, NotRequiredUniqueKeyTest, TestLazyCores, TestSolrQueryParserDefaultOperatorResource, StatelessScriptUpdateProcessorFactoryTest, TestMaxScoreQueryParser, SOLR749Test, EnumFieldTest, TestFieldCollectionResource, TestIndexSearcher, OverseerTest, BasicDistributedZkTest] [junit4] Completed in 72.47s, 1 test, 1 failure <<< FAILURES! [...truncated 1163 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:491: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:471: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:60: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:496: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1358: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:961: There were test failures: 429 suites, 1772 tests, 1 error, 1 failure, 54 ignored (32 assumptions) Total time: 163 minutes 35 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/jdk1.7.0_67 -client -XX:+UseConcMarkSweepGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
