You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2014/12/19 22:12:49 UTC
[JENKINS] Lucene-Solr-NightlyTests-5.x - Build # 708 - Still
Failing
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 SocketConnector@127.0.0.1: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 SocketConnector@127.0.0.1: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 SocketConnector@127.0.0.1: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