Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.x/708/
2 tests failed. REGRESSION: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testDistribSearch Error Message: IOException occured when talking to server at: http://127.0.0.1:53787/disj/m/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:53787/disj/m/collection1 at __randomizedtesting.SeedInfo.seed([9B00FFD089472389:1AE671C8FE1843B5]:0) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:580) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:213) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209) at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91) at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301) at org.apache.solr.cloud.CloudInspectUtil.compareResults(CloudInspectUtil.java:223) at org.apache.solr.cloud.CloudInspectUtil.compareResults(CloudInspectUtil.java:165) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrServer(FullSolrCloudDistribCmdsTest.java:414) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.doTest(FullSolrCloudDistribCmdsTest.java:144) 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:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: java.net.SocketException: Broken pipe at java.net.SocketOutputStream.socketWrite0(Native Method) at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:113) at java.net.SocketOutputStream.write(SocketOutputStream.java:159) at org.apache.http.impl.io.AbstractSessionOutputBuffer.flushBuffer(AbstractSessionOutputBuffer.java:159) at org.apache.http.impl.io.AbstractSessionOutputBuffer.writeLine(AbstractSessionOutputBuffer.java:258) at org.apache.http.impl.io.HttpRequestWriter.writeHeadLine(HttpRequestWriter.java:59) at org.apache.http.impl.io.HttpRequestWriter.writeHeadLine(HttpRequestWriter.java:46) at org.apache.http.impl.io.AbstractMessageWriter.write(AbstractMessageWriter.java:109) at org.apache.http.impl.AbstractHttpClientConnection.sendRequestHeader(AbstractHttpClientConnection.java:254) at org.apache.http.impl.conn.DefaultClientConnection.sendRequestHeader(DefaultClientConnection.java:270) at org.apache.http.impl.conn.ManagedClientConnectionImpl.sendRequestHeader(ManagedClientConnectionImpl.java:209) at org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:203) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:121) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:463) ... 49 more FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch Error Message: Error from server at http://127.0.0.1:62353/eu_: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1 Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error from server at http://127.0.0.1:62353/eu_: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1 at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:566) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:213) at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:650) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:207) 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:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 10290 lines...] [junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest [junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/init-core-data-001 [junit4] 2> 1317958 T7215 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /disj/m [junit4] 2> 1317962 T7215 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 1317963 T7215 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1317964 T7216 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 1318064 T7215 oasc.ZkTestServer.run start zk server on port:45440 [junit4] 2> 1318065 T7215 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1318066 T7215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1318070 T7223 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@658f7138 name:ZooKeeperConnection Watcher:127.0.0.1:45440 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1318070 T7215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1318071 T7215 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1318071 T7215 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 1318074 T7215 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1318075 T7215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1318076 T7226 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@59fb678 name:ZooKeeperConnection Watcher:127.0.0.1:45440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1318077 T7215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1318077 T7215 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1318077 T7215 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 1318079 T7215 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 1318081 T7215 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 1318082 T7215 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 1318084 T7215 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1318084 T7215 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1318087 T7215 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 1318088 T7215 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 1318190 T7215 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1318190 T7215 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1318192 T7215 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1318192 T7215 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 1318194 T7215 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1318195 T7215 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 1318196 T7215 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1318197 T7215 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 1318198 T7215 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1318199 T7215 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1318200 T7215 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1318201 T7215 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1318202 T7215 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1318203 T7215 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1318205 T7215 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1318205 T7215 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1318207 T7215 oasc.AbstractZkTestCase.putConfig put /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1318207 T7215 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 1319316 T7215 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1319320 T7215 oejs.AbstractConnector.doStart Started [email protected]:53787 [junit4] 2> 1319320 T7215 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 1319320 T7215 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1319321 T7215 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-002 [junit4] 2> 1319321 T7215 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-002/' [junit4] 2> 1319352 T7215 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-002/solr.xml [junit4] 2> 1319407 T7215 oasc.CoreContainer.<init> New CoreContainer 206050928 [junit4] 2> 1319407 T7215 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-002/] [junit4] 2> 1319408 T7215 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1319408 T7215 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 1319408 T7215 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1319409 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1319409 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 1319409 T7215 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1319409 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1319409 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1319410 T7215 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1319410 T7215 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1319410 T7215 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 1319411 T7215 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1319411 T7215 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1319411 T7215 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1319411 T7215 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:45440/solr [junit4] 2> 1319412 T7215 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1319412 T7215 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1319413 T7215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1319414 T7238 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a75961e name:ZooKeeperConnection Watcher:127.0.0.1:45440 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1319415 T7215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1319415 T7215 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1319417 T7215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1319418 T7241 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a5d5cff name:ZooKeeperConnection Watcher:127.0.0.1:45440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1319418 T7215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1319419 T7215 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 1319420 T7215 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 1319422 T7215 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 1319423 T7215 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 1319424 T7215 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 1319426 T7215 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 1319427 T7215 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53787_disj%2Fm [junit4] 2> 1319427 T7215 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53787_disj%2Fm [junit4] 2> 1319428 T7215 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 1319429 T7215 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 1319430 T7215 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 1319431 T7215 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53787_disj%2Fm [junit4] 2> 1319432 T7215 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 1319433 T7215 oasc.Overseer.start Overseer (id=92996853773172739-127.0.0.1:53787_disj%2Fm-n_0000000000) starting [junit4] 2> 1319434 T7215 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 1319439 T7215 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1319439 T7243 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 1319440 T7215 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 1319441 T7215 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 1319442 T7215 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1319444 T7242 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 1319448 T7245 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 1319448 T7245 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1319449 T7245 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 1319449 T7241 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1319451 T7242 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "numShards":"1", [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:53787_disj%2Fm", [junit4] 2> "base_url":"http://127.0.0.1:53787/disj/m";, [junit4] 2> "state":"down"} current state version: 0 [junit4] 2> 1319452 T7242 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "numShards":"1", [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:53787_disj%2Fm", [junit4] 2> "base_url":"http://127.0.0.1:53787/disj/m";, [junit4] 2> "state":"down"} [junit4] 2> 1319452 T7242 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 1319453 T7242 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 1319454 T7241 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> 1320449 T7245 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 1320450 T7245 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 1320450 T7245 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 1320450 T7245 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 1320451 T7245 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1320451 T7245 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-002/collection1/' [junit4] 2> 1320453 T7245 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 1320453 T7245 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 1320454 T7245 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 1320476 T7245 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 1320478 T7245 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 1320510 T7245 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 1320577 T7245 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1320678 T7245 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1320697 T7245 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1321047 T7245 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1321050 T7245 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1321052 T7245 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1321057 T7245 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1321076 T7245 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1321076 T7245 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1321077 T7245 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../../usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-002/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/control/data/ [junit4] 2> 1321077 T7245 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dd300ce [junit4] 2> 1321078 T7245 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/control/data [junit4] 2> 1321078 T7245 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/control/data/index/ [junit4] 2> 1321078 T7245 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 1321079 T7245 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/control/data/index [junit4] 2> 1321079 T7245 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1321080 T7245 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@d9bcc8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56f8eee7; maxCacheMB=0.5087890625 maxMergeSizeMB=0.8896484375)),segFN=segments_1,generation=1} [junit4] 2> 1321080 T7245 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1321086 T7245 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1321086 T7245 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1321087 T7245 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1321087 T7245 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1321087 T7245 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1321087 T7245 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1321088 T7245 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1321088 T7245 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1321088 T7245 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1321089 T7245 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1321089 T7245 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1321090 T7245 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1321090 T7245 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1321091 T7245 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 1321091 T7245 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 1321092 T7245 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 1321092 T7245 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 1321093 T7245 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 1321094 T7245 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 1321104 T7245 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 1321105 T7245 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 1321106 T7245 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 1321106 T7245 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 1321106 T7245 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 1321107 T7245 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 1321107 T7245 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 1321108 T7245 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 1321108 T7245 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 1321127 T7245 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1321129 T7245 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1321131 T7245 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1321133 T7245 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1321138 T7245 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1321138 T7245 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1321139 T7245 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=1.15625, floorSegmentMB=1.765625, forceMergeDeletesPctAllowed=24.908727684457737, segmentsPerTier=6.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2768448804333449 [junit4] 2> 1321140 T7245 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@d9bcc8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56f8eee7; maxCacheMB=0.5087890625 maxMergeSizeMB=0.8896484375)),segFN=segments_1,generation=1} [junit4] 2> 1321140 T7245 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1321140 T7245 oass.SolrIndexSearcher.<init> Opening Searcher@7464d0bb[collection1] main [junit4] 2> 1321141 T7245 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 1321142 T7245 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1321142 T7245 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1321142 T7245 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1321143 T7245 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 1321143 T7245 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1321143 T7245 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 1321144 T7245 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1321144 T7245 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json [junit4] 2> 1321146 T7245 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json [junit4] 2> 1321146 T7245 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1321147 T7245 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1321147 T7245 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1321148 T7246 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7464d0bb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1321148 T7245 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1321149 T7249 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53787/disj/m collection:control_collection shard:shard1 [junit4] 2> 1321149 T7215 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3 [junit4] 2> 1321150 T7215 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1321150 T7249 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 1321151 T7215 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1321152 T7215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1321154 T7252 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4de4e3b5 name:ZooKeeperConnection Watcher:127.0.0.1:45440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1321154 T7215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1321154 T7215 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1321155 T7215 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1321156 T7249 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 1321158 T7241 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1321158 T7249 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 1321158 T7249 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C628 name=collection1 org.apache.solr.core.SolrCore@1de1914e url=http://127.0.0.1:53787/disj/m/collection1 node=127.0.0.1:53787_disj%2Fm C628_STATE=coll:control_collection core:collection1 props:{core=collection1, node_name=127.0.0.1:53787_disj%2Fm, base_url=http://127.0.0.1:53787/disj/m, state=down} [junit4] 2> 1321158 T7249 C628 P53787 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53787/disj/m/collection1/ [junit4] 2> 1321159 T7242 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> 1321159 T7215 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1321159 T7249 C628 P53787 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 1321159 T7215 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 1321160 T7249 C628 P53787 oasc.SyncStrategy.syncToMe http://127.0.0.1:53787/disj/m/collection1/ has no replicas [junit4] 2> 1321160 T7215 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1321160 T7241 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> 1321160 T7252 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> 1321161 T7215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1321160 T7249 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53787/disj/m/collection1/ shard1 [junit4] 2> 1321162 T7249 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1321163 T7255 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4fa4392b name:ZooKeeperConnection Watcher:127.0.0.1:45440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1321164 T7215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1321164 T7215 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1321166 T7256 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1321167 T7242 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "stateFormat":2} current state version: 2 [junit4] 2> 1321167 T7242 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 1321170 T7242 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:53787/disj/m";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 1321170 T7242 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json [junit4] 2> 1321172 T7252 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> 1321175 T7256 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1321175 T7252 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> 1321218 T7249 oasc.ZkController.register We are http://127.0.0.1:53787/disj/m/collection1/ and leader is http://127.0.0.1:53787/disj/m/collection1/ [junit4] 2> 1321219 T7249 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53787/disj/m [junit4] 2> 1321219 T7249 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 1321219 T7249 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 1321219 T7249 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1321221 T7241 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1321222 T7242 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "shard":"shard1", [junit4] 2> "numShards":"3", [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:53787_disj%2Fm", [junit4] 2> "base_url":"http://127.0.0.1:53787/disj/m";, [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 1321223 T7242 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "shard":"shard1", [junit4] 2> "numShards":"3", [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:53787_disj%2Fm", [junit4] 2> "base_url":"http://127.0.0.1:53787/disj/m";, [junit4] 2> "state":"active"} [junit4] 2> 1321326 T7241 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> 1321326 T7252 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> 1322390 T7215 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 1322391 T7215 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1322394 T7215 oejs.AbstractConnector.doStart Started [email protected]:37537 [junit4] 2> 1322394 T7215 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 1322395 T7215 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1322395 T7215 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-003 [junit4] 2> 1322395 T7215 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-003/' [junit4] 2> 1322425 T7215 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-003/solr.xml [junit4] 2> 1322483 T7215 oasc.CoreContainer.<init> New CoreContainer 1434421756 [junit4] 2> 1322484 T7215 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-003/] [junit4] 2> 1322485 T7215 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1322485 T7215 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 1322485 T7215 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1322485 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1322486 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 1322486 T7215 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1322486 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1322486 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1322487 T7215 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1322487 T7215 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1322487 T7215 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 1322488 T7215 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1322488 T7215 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1322488 T7215 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1322488 T7215 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:45440/solr [junit4] 2> 1322489 T7215 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1322489 T7215 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1322490 T7215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1322491 T7268 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3619784b name:ZooKeeperConnection Watcher:127.0.0.1:45440 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1322492 T7215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1322493 T7215 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1322495 T7215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1322496 T7271 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2514291a name:ZooKeeperConnection Watcher:127.0.0.1:45440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1322496 T7215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1322501 T7215 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1323506 T7215 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37537_disj%2Fm [junit4] 2> 1323507 T7215 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37537_disj%2Fm [junit4] 2> 1323511 T7215 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 1323519 T7272 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 1323520 T7272 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1323521 T7241 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1323522 T7272 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 1323522 T7242 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "numShards":"3", [junit4] 2> "collection":"collection1", [junit4] 2> "node_name":"127.0.0.1:37537_disj%2Fm", [junit4] 2> "base_url":"http://127.0.0.1:37537/disj/m";, [junit4] 2> "state":"down"} current state version: 5 [junit4] 2> 1323522 T7272 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 1323523 T7242 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "numShards":"3", [junit4] 2> "collection":"collection1", [junit4] 2> "node_name":"127.0.0.1:37537_disj%2Fm", [junit4] 2> "base_url":"http://127.0.0.1:37537/disj/m";, [junit4] 2> "state":"down"} [junit4] 2> 1323523 T7272 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 1323523 T7242 oasco.ReplicaMutator.updateState Collection already exists with numShards=3 [junit4] 2> 1323524 T7242 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard3 [junit4] 2> 1323524 T7272 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 1323525 T7272 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 1323525 T7242 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 1323526 T7271 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 1323527 T7271 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 1324525 T7272 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 1324526 T7272 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 1324527 T7272 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 1324527 T7272 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1324528 T7272 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1324528 T7272 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-003/collection1/' [junit4] 2> 1324529 T7272 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 1324530 T7272 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 1324531 T7272 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 1324566 T7272 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 1324569 T7272 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 1324612 T7272 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 1324685 T7272 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1324786 T7272 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1324803 T7272 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1325173 T7272 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1325176 T7272 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1325178 T7272 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1325182 T7272 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1325201 T7272 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1325202 T7272 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1325202 T7272 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-003/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/jetty1/ [junit4] 2> 1325203 T7272 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dd300ce [junit4] 2> 1325203 T7272 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/jetty1 [junit4] 2> 1325204 T7272 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/jetty1/index/ [junit4] 2> 1325204 T7272 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 1325205 T7272 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/jetty1/index [junit4] 2> 1325205 T7272 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1325206 T7272 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@d440fb9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f64417c; maxCacheMB=0.5087890625 maxMergeSizeMB=0.8896484375)),segFN=segments_1,generation=1} [junit4] 2> 1325207 T7272 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1325212 T7272 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1325213 T7272 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 1325214 T7272 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1325214 T7272 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1325214 T7272 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1325214 T7272 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1325215 T7272 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1325215 T7272 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1325216 T7272 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 1325217 T7272 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1325217 T7272 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1325218 T7272 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1325219 T7272 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 1325220 T7272 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 1325221 T7272 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 1325222 T7272 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 1325223 T7272 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 1325223 T7272 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 1325225 T7272 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 1325225 T7272 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 1325226 T7272 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 1325226 T7272 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 1325227 T7272 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 1325227 T7272 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 1325228 T7272 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 1325229 T7272 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 1325229 T7272 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 1325230 T7272 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 1325251 T7272 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1325253 T7272 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1325255 T7272 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1325257 T7272 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 1325262 T7272 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 1325262 T7272 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 1325263 T7272 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=1.15625, floorSegmentMB=1.765625, forceMergeDeletesPctAllowed=24.908727684457737, segmentsPerTier=6.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2768448804333449 [junit4] 2> 1325264 T7272 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@d440fb9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f64417c; maxCacheMB=0.5087890625 maxMergeSizeMB=0.8896484375)),segFN=segments_1,generation=1} [junit4] 2> 1325264 T7272 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 1325264 T7272 oass.SolrIndexSearcher.<init> Opening Searcher@492860e7[collection1] main [junit4] 2> 1325265 T7272 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1325266 T7272 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1325266 T7272 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 1325266 T7272 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1325266 T7272 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 1325267 T7272 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1325267 T7272 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json [junit4] 2> 1325268 T7272 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1325268 T7272 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed [junit4] 2> 1325268 T7272 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 1325268 T7272 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 1325270 T7273 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@492860e7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1325270 T7272 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 1325271 T7276 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:37537/disj/m collection:collection1 shard:shard3 [junit4] 2> 1325272 T7215 oass.SolrDispatchFilter.init user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3 [junit4] 2> 1325272 T7276 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election [junit4] 2> 1325272 T7215 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 1325277 T7276 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3 [junit4] 2> 1325278 T7241 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1325279 T7276 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 1325279 T7276 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C629 name=collection1 org.apache.solr.core.SolrCore@13ad64ea url=http://127.0.0.1:37537/disj/m/collection1 node=127.0.0.1:37537_disj%2Fm C629_STATE=coll:collection1 core:collection1 props:{core=collection1, node_name=127.0.0.1:37537_disj%2Fm, base_url=http://127.0.0.1:37537/disj/m, state=down} [junit4] 2> 1325279 T7276 C629 P37537 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:37537/disj/m/collection1/ [junit4] 2> 1325279 T7242 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard3", [junit4] 2> "collection":"collection1"} current state version: 5 [junit4] 2> 1325279 T7276 C629 P37537 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 1325280 T7276 C629 P37537 oasc.SyncStrategy.syncToMe http://127.0.0.1:37537/disj/m/collection1/ has no replicas [junit4] 2> 1325280 T7276 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:37537/disj/m/collection1/ shard3 [junit4] 2> 1325280 T7276 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3 [junit4] 2> 1325281 T7242 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 1325282 T7271 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 1325282 T7271 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 [junit4] 2> 1325285 T7242 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard3", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:37537/disj/m";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 5 [junit4] 2> 1325286 T7242 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 2 [junit4] 2> 1325287 T7271 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 1325288 T7271 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 1325290 T7241 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1325335 T7276 oasc.ZkController.register We are http://127.0.0.1:37537/disj/m/collection1/ and leader is http://127.0.0.1:37537/disj/m/collection1/ [junit4] 2> 1325336 T7276 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37537/disj/m [junit4] 2> 1325336 T7276 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 1325336 T7276 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 1325336 T7276 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1325338 T7241 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1325339 T7242 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "shard":"shard3", [junit4] 2> "numShards":"3", [junit4] 2> "collection":"collection1", [junit4] 2> "node_name":"127.0.0.1:37537_disj%2Fm", [junit4] 2> "base_url":"http://127.0.0.1:37537/disj/m";, [junit4] 2> "state":"active"} current state version: 5 [junit4] 2> 1325340 T7242 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "shard":"shard3", [junit4] 2> "numShards":"3", [junit4] 2> "collection":"collection1", [junit4] 2> "node_name":"127.0.0.1:37537_disj%2Fm", [junit4] 2> "base_url":"http://127.0.0.1:37537/disj/m";, [junit4] 2> "state":"active"} [junit4] 2> 1325341 T7276 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 1325341 T7242 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 3 [junit4] 2> 1325342 T7271 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 1325343 T7271 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 1326467 T7215 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 1326468 T7215 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 1326470 T7215 oejs.AbstractConnector.doStart Started [email protected]:60064 [junit4] 2> 1326471 T7215 oass.SolrDispatchFilter.init SolrDispatchFilter.init() [junit4] 2> 1326471 T7215 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1326471 T7215 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-004 [junit4] 2> 1326472 T7215 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-004/' [junit4] 2> 1326504 T7215 oasc.ConfigSolr.fromFile Loading container configuration from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-004/solr.xml [junit4] 2> 1326563 T7215 oasc.CoreContainer.<init> New CoreContainer 166696062 [junit4] 2> 1326563 T7215 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-004/] [junit4] 2> 1326564 T7215 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 1326564 T7215 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 1326565 T7215 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 1326565 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 1326565 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 1326565 T7215 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 1326565 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 1326566 T7215 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 1326566 T7215 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 1326566 T7215 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 1326567 T7215 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 1326567 T7215 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1326567 T7215 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1326567 T7215 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 1326568 T7215 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:45440/solr [junit4] 2> 1326568 T7215 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 1326568 T7215 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 1326569 T7215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1326571 T7288 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b0e72ae name:ZooKeeperConnection Watcher:127.0.0.1:45440 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1326572 T7215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1326572 T7215 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 1326573 T7215 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 1326575 T7291 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7cb30a1c name:ZooKeeperConnection Watcher:127.0.0.1:45440/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1326575 T7215 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 1326580 T7215 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 1327586 T7215 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60064_disj%2Fm [junit4] 2> 1327587 T7215 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60064_disj%2Fm [junit4] 2> 1327591 T7215 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 1327599 T7292 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 1327600 T7292 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 1327601 T7241 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1327602 T7292 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 1327602 T7242 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "numShards":"3", [junit4] 2> "collection":"collection1", [junit4] 2> "node_name":"127.0.0.1:60064_disj%2Fm", [junit4] 2> "base_url":"http://127.0.0.1:60064/disj/m";, [junit4] 2> "state":"down"} current state version: 5 [junit4] 2> 1327602 T7292 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 1327603 T7242 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "numShards":"3", [junit4] 2> "collection":"collection1", [junit4] 2> "node_name":"127.0.0.1:60064_disj%2Fm", [junit4] 2> "base_url":"http://127.0.0.1:60064/disj/m";, [junit4] 2> "state":"down"} [junit4] 2> 1327603 T7292 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 1327603 T7242 oasco.ReplicaMutator.updateState Collection already exists with numShards=3 [junit4] 2> 1327604 T7242 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 1327604 T7292 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 1327605 T7292 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 1327605 T7242 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 4 [junit4] 2> 1327606 T7291 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 1327606 T7271 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 1327606 T7291 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 1327606 T7271 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 1328605 T7292 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 1328606 T7292 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 1328606 T7292 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 1328607 T7292 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 1328607 T7292 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1328608 T7292 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-004/collection1/' [junit4] 2> 1328609 T7292 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 1328610 T7292 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 1328611 T7292 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 1328644 T7292 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 1328647 T7292 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 1328685 T7292 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 1328752 T7292 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 1328853 T7292 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1328867 T7292 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 1329224 T7292 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 1329227 T7292 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 1329229 T7292 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1329234 T7292 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 1329254 T7292 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1329255 T7292 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1329255 T7292 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-004/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/jetty2/ [junit4] 2> 1329255 T7292 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6dd300ce [junit4] 2> 1329256 T7292 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/jetty2 [junit4] 2> 1329257 T7292 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/jetty2/index/ [junit4] 2> 1329257 T7292 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 1329258 T7292 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J3/temp/solr.cloud.FullSolrCloudDistribCmdsTest-9B00FFD089472389-001/tempDir-001/jetty2/index [junit4] 2> 1329259 T7292 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1329260 T7292 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@6670be6a lockFactory=org.apac [...truncated too long message...] lrj.impl.HttpSolrServer.request(HttpSolrServer.java:213) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.doRequest(LBHttpSolrServer.java:354) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:313) [junit4] > ... 6 moreThrowable #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=2428, name=Thread-889, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:62353/eu_, http://127.0.0.1:62368/eu_, http://127.0.0.1:62358/eu_, http://127.0.0.1:62376/eu_, http://127.0.0.1:62346/eu_] [junit4] > at __randomizedtesting.SeedInfo.seed([9B00FFD089472389]:0) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:1116) [junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:62353/eu_, http://127.0.0.1:62368/eu_, http://127.0.0.1:62358/eu_, http://127.0.0.1:62376/eu_, http://127.0.0.1:62346/eu_] [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:333) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrServer.sendRequest(CloudSolrServer.java:1015) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrServer.requestWithRetryOnStaleState(CloudSolrServer.java:793) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:736) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1640) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1661) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:1106) [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error from server at http://127.0.0.1:62376/eu_: Could not fully create collection: awholynewstresscollection_collection2_1 [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:566) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:213) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.doRequest(LBHttpSolrServer.java:354) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:313) [junit4] > ... 6 moreThrowable #7: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=2430, name=Thread-891, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:62353/eu_, http://127.0.0.1:62368/eu_, http://127.0.0.1:62358/eu_, http://127.0.0.1:62376/eu_, http://127.0.0.1:62346/eu_] [junit4] > at __randomizedtesting.SeedInfo.seed([9B00FFD089472389]:0) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:1116) [junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:62353/eu_, http://127.0.0.1:62368/eu_, http://127.0.0.1:62358/eu_, http://127.0.0.1:62376/eu_, http://127.0.0.1:62346/eu_] [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:333) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrServer.sendRequest(CloudSolrServer.java:1015) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrServer.requestWithRetryOnStaleState(CloudSolrServer.java:793) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:736) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1640) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1661) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:1106) [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Error from server at http://127.0.0.1:62376/eu_: Could not fully create collection: awholynewstresscollection_collection4_1 [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:566) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:213) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.doRequest(LBHttpSolrServer.java:354) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:313) [junit4] > ... 6 more [junit4] 2> 1928776 T1969 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest-9B00FFD089472389-001 [junit4] 2> 911972 T1968 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {timestamp=PostingsFormat(name=MockRandom), id=PostingsFormat(name=LuceneVarGapFixedInterval), other_tl1=PostingsFormat(name=Direct), a_t=PostingsFormat(name=MockRandom), multiDefault=PostingsFormat(name=MockRandom), range_facet_l=PostingsFormat(name=MockRandom), text=BlockTreeOrds(blocksize=128), intDefault=PostingsFormat(name=Direct), a_i1=PostingsFormat(name=MockRandom), _version_=PostingsFormat(name=Direct)}, docValues:{timestamp=DocValuesFormat(name=Asserting)}, sim=DefaultSimilarity, locale=in_ID, timezone=Etc/GMT+10 [junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=16,threads=1,free=203238472,total=383254528 [junit4] 2> NOTE: All tests run in this JVM: [TestReload, TestPivotHelperCode, TestSchemaNameResource, TestFreeTextSuggestions, TestRandomDVFaceting, DistributedQueryComponentCustomSortTest, TestSolrXmlPersistence, LeaderElectionIntegrationTest, TestBulkSchemaAPI, StandardRequestHandlerTest, TestDocumentBuilder, SolrCoreCheckLockOnStartupTest, TestConfig, NoCacheHeaderTest, HardAutoCommitTest, MoreLikeThisHandlerTest, TestInfoStreamLogging, HdfsUnloadDistributedZkTest, TestUniqueKeyFieldResource, ScriptEngineTest, DistributedFacetPivotLongTailTest, TestHashPartitioner, SpatialFilterTest, SolrRequestParserTest, DirectUpdateHandlerOptimizeTest, TestRemoteStreaming, SynonymTokenizerTest, TestDefaultSimilarityFactory, TestManagedStopFilterFactory, TestCSVResponseWriter, FileBasedSpellCheckerTest, TestPhraseSuggestions, TestFieldTypeResource, EchoParamsTest, SolrXmlInZkTest, DistributedDebugComponentTest, AssignTest, TestSchemaSimilarityResource, TestHighlightDedupGrouping, HighlighterConfigTest, TestSolrDeletionPolicy2, HdfsRecoveryZkTest, AliasIntegrationTest, TestManagedResource, OverseerStatusTest, StatsComponentTest, ChaosMonkeyNothingIsSafeTest, CachingDirectoryFactoryTest, AlternateDirectoryTest, ReturnFieldsTest, TestCollationFieldDocValues, TestReversedWildcardFilterFactory, TestPHPSerializedResponseWriter, TestLMDirichletSimilarityFactory, TestIntervalFaceting, TestComponentsName, CollectionsAPIDistributedZkTest] [junit4] Completed on J2 in 912.26s, 1 test, 1 error <<< FAILURES! [...truncated 759 lines...] BUILD FAILED /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:536: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:477: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/build.xml:61: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/extra-targets.xml:39: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/build.xml:190: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/solr/common-build.xml:508: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:1363: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.x/lucene/common-build.xml:970: There were test failures: 452 suites, 1852 tests, 2 errors, 57 ignored (10 assumptions) Total time: 173 minutes 29 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Sending artifact delta relative to Lucene-Solr-NightlyTests-5.x #705 Archived 5 artifacts Archive block size is 32768 Received 0 blocks and 772409382 bytes Compression is 0.0% Took 2 min 18 sec Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
