You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/05/15 18:13:38 UTC
[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0_05) - Build #
10307 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10307/
Java: 64bit/jdk1.8.0_05 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testDistribSearch
Error Message:
Could not find collection : collection1
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Could not find collection : collection1
at __randomizedtesting.SeedInfo.seed([4DDA8A25E7338422:CC3C043D906CE41E]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:554)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:210)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:206)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.doRequest(LBHttpSolrServer.java:340)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:301)
at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:636)
at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrServer(FullSolrCloudDistribCmdsTest.java:349)
at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.doTest(FullSolrCloudDistribCmdsTest.java:145)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
at sun.reflect.GeneratedMethodAccessor50.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:360)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11350 lines...]
[junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/init-core-data-001
[junit4] 2> 830636 T4400 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /ek/
[junit4] 2> 830639 T4400 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 830639 T4400 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 830639 T4401 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 830739 T4400 oasc.ZkTestServer.run start zk server on port:52145
[junit4] 2> 830740 T4400 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 831000 T4407 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e001e68 name:ZooKeeperConnection Watcher:127.0.0.1:52145 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 831000 T4400 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 831001 T4400 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 831004 T4400 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 831005 T4409 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2a3c8d98 name:ZooKeeperConnection Watcher:127.0.0.1:52145/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 831005 T4400 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 831005 T4400 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 831007 T4400 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 831008 T4400 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 831008 T4400 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 831009 T4400 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 831010 T4400 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 831011 T4400 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 831012 T4400 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 831013 T4400 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 831013 T4400 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 831015 T4400 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 831015 T4400 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 831016 T4400 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 831016 T4400 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 831017 T4400 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 831017 T4400 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 831019 T4400 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 831019 T4400 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 831020 T4400 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 831020 T4400 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 831021 T4400 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 831022 T4400 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 831023 T4400 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 831023 T4400 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 831026 T4400 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 831026 T4400 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 831238 T4400 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 831243 T4400 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:36462
[junit4] 2> 831244 T4400 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 831244 T4400 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 831244 T4400 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-002
[junit4] 2> 831244 T4400 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-002/'
[junit4] 2> 831253 T4400 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-002/solr.xml
[junit4] 2> 831262 T4400 oasc.CoreContainer.<init> New CoreContainer 1296791709
[junit4] 2> 831262 T4400 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-002/]
[junit4] 2> 831262 T4400 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 831263 T4400 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 831263 T4400 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 831263 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 831263 T4400 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 831263 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 831264 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 831264 T4400 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 831264 T4400 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 831265 T4400 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 831265 T4400 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 831265 T4400 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 831265 T4400 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52145/solr
[junit4] 2> 831266 T4400 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 831266 T4400 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 831267 T4420 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@295c161e name:ZooKeeperConnection Watcher:127.0.0.1:52145 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 831267 T4400 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 831269 T4400 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 831270 T4422 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@53393204 name:ZooKeeperConnection Watcher:127.0.0.1:52145/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 831270 T4400 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 831271 T4400 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 831273 T4400 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 831274 T4400 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 831275 T4400 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 831277 T4400 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 831278 T4400 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 831279 T4400 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36462_ek
[junit4] 2> 831280 T4400 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36462_ek
[junit4] 2> 831281 T4400 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 831282 T4400 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 831283 T4400 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:36462_ek
[junit4] 2> 831284 T4400 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 831285 T4400 oasc.Overseer.start Overseer (id=91761535916507139-127.0.0.1:36462_ek-n_0000000000) starting
[junit4] 2> 831288 T4400 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 831294 T4424 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 831294 T4400 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 831296 T4400 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 831297 T4400 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 831298 T4423 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 831300 T4425 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 831300 T4425 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 831300 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 831300 T4425 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 831301 T4423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 831301 T4423 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:36462/ek",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:36462_ek",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 831301 T4423 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 831302 T4423 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 831303 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 831303 T4422 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> 832301 T4425 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 832301 T4425 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 832302 T4425 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 832302 T4425 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 832303 T4425 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 832303 T4425 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-002/collection1/'
[junit4] 2> 832303 T4425 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 832303 T4425 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 832304 T4425 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 832321 T4425 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 832330 T4425 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 832331 T4425 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 832335 T4425 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 832442 T4425 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 832443 T4425 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 832443 T4425 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 832445 T4425 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 832455 T4425 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 832456 T4425 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 832456 T4425 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-002/collection1/, dataDir=./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/control/data/
[junit4] 2> 832456 T4425 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@41d12759
[junit4] 2> 832457 T4425 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/control/data
[junit4] 2> 832457 T4425 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/control/data/index/
[junit4] 2> 832457 T4425 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 832458 T4425 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/control/data/index
[junit4] 2> 832458 T4425 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=144321030, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 832459 T4425 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@26ece7c0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70f225dd),segFN=segments_1,generation=1}
[junit4] 2> 832459 T4425 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 832461 T4425 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 832462 T4425 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 832462 T4425 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 832462 T4425 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 832462 T4425 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 832462 T4425 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 832462 T4425 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 832463 T4425 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 832463 T4425 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 832463 T4425 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 832463 T4425 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 832463 T4425 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 832463 T4425 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 832464 T4425 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 832464 T4425 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 832464 T4425 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 832465 T4425 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 832466 T4425 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 832468 T4425 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 832468 T4425 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 832468 T4425 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 832469 T4425 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@26ece7c0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70f225dd),segFN=segments_1,generation=1}
[junit4] 2> 832469 T4425 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 832470 T4425 oass.SolrIndexSearcher.<init> Opening Searcher@3ae02fcc[collection1] main
[junit4] 2> 832470 T4425 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 832471 T4425 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 832471 T4425 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 832471 T4425 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 832471 T4425 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 832472 T4425 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 832472 T4425 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 832472 T4425 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 832473 T4425 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 832473 T4425 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 832474 T4426 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3ae02fcc[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 832475 T4425 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 832475 T4429 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:36462/ek collection:control_collection shard:shard1
[junit4] 2> 832475 T4400 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 832476 T4400 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 832476 T4429 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 832476 T4400 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 832478 T4431 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@43e61746 name:ZooKeeperConnection Watcher:127.0.0.1:52145/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 832478 T4400 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 832479 T4400 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 832480 T4429 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 832481 T4400 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 832481 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 832481 T4429 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 832481 T4429 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1580 name=collection1 org.apache.solr.core.SolrCore@192fa416 url=http://127.0.0.1:36462/ek/collection1 node=127.0.0.1:36462_ek C1580_STATE=coll:control_collection core:collection1 props:{state=down, base_url=http://127.0.0.1:36462/ek, core=collection1, node_name=127.0.0.1:36462_ek}
[junit4] 2> 832482 T4429 C1580 P36462 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:36462/ek/collection1/
[junit4] 2> 832482 T4429 C1580 P36462 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 832482 T4429 C1580 P36462 oasc.SyncStrategy.syncToMe http://127.0.0.1:36462/ek/collection1/ has no replicas
[junit4] 2> 832482 T4429 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:36462/ek/collection1/ shard1
[junit4] 2> 832482 T4429 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 832483 T4423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 832485 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 832485 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 832487 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 832588 T4422 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> 832588 T4431 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> 832636 T4429 oasc.ZkController.register We are http://127.0.0.1:36462/ek/collection1/ and leader is http://127.0.0.1:36462/ek/collection1/
[junit4] 2> 832636 T4429 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:36462/ek
[junit4] 2> 832637 T4429 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 832637 T4429 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 832637 T4429 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 832638 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 832638 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 832638 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 832639 T4429 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 832639 T4423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 832640 T4423 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:36462/ek",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:36462_ek",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 832641 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 832708 T4400 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 832708 T4400 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 832710 T4400 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:32783
[junit4] 2> 832711 T4400 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 832712 T4400 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 832712 T4400 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-003
[junit4] 2> 832712 T4400 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-003/'
[junit4] 2> 832722 T4400 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-003/solr.xml
[junit4] 2> 832730 T4400 oasc.CoreContainer.<init> New CoreContainer 451084613
[junit4] 2> 832730 T4400 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-003/]
[junit4] 2> 832731 T4400 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 832731 T4400 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 832731 T4400 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 832731 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 832732 T4400 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 832732 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 832732 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 832732 T4400 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 832732 T4400 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 832733 T4400 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 832733 T4400 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 832733 T4400 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 832734 T4400 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52145/solr
[junit4] 2> 832734 T4400 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 832735 T4400 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 832736 T4442 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@150e6ee5 name:ZooKeeperConnection Watcher:127.0.0.1:52145 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 832736 T4400 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 832738 T4400 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 832743 T4444 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@20e8dbd name:ZooKeeperConnection Watcher:127.0.0.1:52145/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 832744 T4400 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 832744 T4422 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> 832744 T4431 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> 832748 T4400 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 833750 T4400 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32783_ek
[junit4] 2> 833751 T4400 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:32783_ek
[junit4] 2> 833757 T4445 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 833758 T4445 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 833758 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 833758 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 833759 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 833758 T4445 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 833759 T4423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 833760 T4423 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:32783/ek",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:32783_ek",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 833760 T4423 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3]
[junit4] 2> 833760 T4423 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 833761 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 833863 T4422 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 833863 T4444 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 833863 T4431 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 834759 T4445 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 834759 T4445 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 834760 T4445 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 834760 T4445 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 834761 T4445 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 834761 T4445 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-003/collection1/'
[junit4] 2> 834762 T4445 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-003/collection1/lib/.svn/' to classloader
[junit4] 2> 834763 T4445 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-003/collection1/lib/classes/' to classloader
[junit4] 2> 834763 T4445 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-003/collection1/lib/README' to classloader
[junit4] 2> 834787 T4445 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 834800 T4445 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 834801 T4445 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 834808 T4445 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 834916 T4445 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 834917 T4445 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 834917 T4445 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 834919 T4445 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 834929 T4445 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 834929 T4445 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 834929 T4445 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-003/collection1/, dataDir=./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/jetty1/
[junit4] 2> 834930 T4445 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@41d12759
[junit4] 2> 834930 T4445 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/jetty1
[junit4] 2> 834930 T4445 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/jetty1/index/
[junit4] 2> 834930 T4445 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 834931 T4445 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/jetty1/index
[junit4] 2> 834931 T4445 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=144321030, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 834932 T4445 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@67d5893d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7be5e1d5),segFN=segments_1,generation=1}
[junit4] 2> 834932 T4445 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 834934 T4445 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 834934 T4445 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 834934 T4445 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 834935 T4445 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 834935 T4445 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 834935 T4445 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 834935 T4445 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 834935 T4445 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 834935 T4445 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 834936 T4445 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 834936 T4445 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 834936 T4445 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 834936 T4445 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 834937 T4445 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 834937 T4445 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 834937 T4445 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 834937 T4445 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 834939 T4445 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 834940 T4445 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 834940 T4445 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 834940 T4445 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 834941 T4445 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@67d5893d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7be5e1d5),segFN=segments_1,generation=1}
[junit4] 2> 834941 T4445 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 834941 T4445 oass.SolrIndexSearcher.<init> Opening Searcher@4ae9198a[collection1] main
[junit4] 2> 834941 T4445 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 834942 T4445 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 834942 T4445 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 834942 T4445 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 834942 T4445 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 834943 T4445 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 834943 T4445 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 834943 T4445 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 834943 T4445 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 834943 T4445 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 834945 T4446 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ae9198a[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 834946 T4445 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 834947 T4449 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:32783/ek collection:collection1 shard:shard2
[junit4] 2> 834948 T4400 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 834948 T4400 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 834948 T4449 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 834954 T4449 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 834956 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 834956 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 834956 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 834956 T4449 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 834957 T4449 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1581 name=collection1 org.apache.solr.core.SolrCore@44913abc url=http://127.0.0.1:32783/ek/collection1 node=127.0.0.1:32783_ek C1581_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:32783/ek, core=collection1, node_name=127.0.0.1:32783_ek}
[junit4] 2> 834957 T4449 C1581 P32783 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:32783/ek/collection1/
[junit4] 2> 834957 T4423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 834957 T4449 C1581 P32783 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 834957 T4449 C1581 P32783 oasc.SyncStrategy.syncToMe http://127.0.0.1:32783/ek/collection1/ has no replicas
[junit4] 2> 834957 T4449 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:32783/ek/collection1/ shard2
[junit4] 2> 834958 T4449 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 834959 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 834961 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 834962 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 835063 T4422 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 835063 T4431 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 835063 T4444 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 835111 T4449 oasc.ZkController.register We are http://127.0.0.1:32783/ek/collection1/ and leader is http://127.0.0.1:32783/ek/collection1/
[junit4] 2> 835112 T4449 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:32783/ek
[junit4] 2> 835112 T4449 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 835112 T4449 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 835112 T4449 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 835113 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 835113 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 835113 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 835113 T4449 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 835114 T4423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 835114 T4423 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:32783/ek",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:32783_ek",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 835115 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 835221 T4444 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 835221 T4422 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 835221 T4431 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 835224 T4400 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 835225 T4400 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 835226 T4400 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:39239
[junit4] 2> 835228 T4400 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 835228 T4400 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 835228 T4400 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-004
[junit4] 2> 835228 T4400 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-004/'
[junit4] 2> 835238 T4400 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-004/solr.xml
[junit4] 2> 835247 T4400 oasc.CoreContainer.<init> New CoreContainer 673984629
[junit4] 2> 835247 T4400 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-004/]
[junit4] 2> 835248 T4400 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 835248 T4400 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 835248 T4400 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 835249 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 835249 T4400 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 835249 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 835249 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 835249 T4400 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 835250 T4400 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 835250 T4400 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 835251 T4400 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 835251 T4400 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 835251 T4400 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52145/solr
[junit4] 2> 835251 T4400 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 835252 T4400 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 835253 T4460 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@372df18 name:ZooKeeperConnection Watcher:127.0.0.1:52145 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 835253 T4400 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 835255 T4400 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 835256 T4462 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@687a8f4 name:ZooKeeperConnection Watcher:127.0.0.1:52145/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 835257 T4400 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 835261 T4400 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 836263 T4400 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39239_ek
[junit4] 2> 836266 T4400 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:39239_ek
[junit4] 2> 836273 T4463 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 836273 T4463 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 836274 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 836274 T4463 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 836274 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 836275 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 836275 T4423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 836276 T4423 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:39239/ek",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:39239_ek",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 836276 T4423 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=3
[junit4] 2> 836276 T4423 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3
[junit4] 2> 836277 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 836379 T4444 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 836379 T4462 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 836379 T4431 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 836379 T4422 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 837275 T4463 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 837275 T4463 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 837276 T4463 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 837277 T4463 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 837278 T4463 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 837278 T4463 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-004/collection1/'
[junit4] 2> 837279 T4463 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-004/collection1/lib/.svn/' to classloader
[junit4] 2> 837280 T4463 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 837280 T4463 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 837311 T4463 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 837327 T4463 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 837328 T4463 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 837333 T4463 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 837433 T4463 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 837435 T4463 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 837436 T4463 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 837438 T4463 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 837449 T4463 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 837449 T4463 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 837449 T4463 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-004/collection1/, dataDir=./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/jetty2/
[junit4] 2> 837449 T4463 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@41d12759
[junit4] 2> 837450 T4463 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/jetty2
[junit4] 2> 837450 T4463 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/jetty2/index/
[junit4] 2> 837450 T4463 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 837450 T4463 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-001/jetty2/index
[junit4] 2> 837451 T4463 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=144321030, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 837451 T4463 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@116f2843 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c48e39d),segFN=segments_1,generation=1}
[junit4] 2> 837451 T4463 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 837453 T4463 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 837453 T4463 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 837454 T4463 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 837454 T4463 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 837454 T4463 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 837454 T4463 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 837454 T4463 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 837454 T4463 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 837455 T4463 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 837455 T4463 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 837455 T4463 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 837455 T4463 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 837455 T4463 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 837456 T4463 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 837456 T4463 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 837456 T4463 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 837456 T4463 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 837458 T4463 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 837459 T4463 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 837459 T4463 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 837459 T4463 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 837460 T4463 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@116f2843 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7c48e39d),segFN=segments_1,generation=1}
[junit4] 2> 837460 T4463 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 837460 T4463 oass.SolrIndexSearcher.<init> Opening Searcher@4cc6cc81[collection1] main
[junit4] 2> 837460 T4463 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 837461 T4463 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 837461 T4463 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 837461 T4463 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 837461 T4463 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 837462 T4463 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 837462 T4463 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 837462 T4463 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 837462 T4463 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 837462 T4463 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 837464 T4464 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4cc6cc81[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 837466 T4463 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 837466 T4467 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:39239/ek collection:collection1 shard:shard3
[junit4] 2> 837467 T4400 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 837467 T4400 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 837467 T4467 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
[junit4] 2> 837478 T4467 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3
[junit4] 2> 837479 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 837480 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 837480 T4467 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 837480 T4467 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 837480 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> ASYNC NEW_CORE C1582 name=collection1 org.apache.solr.core.SolrCore@339cdac7 url=http://127.0.0.1:39239/ek/collection1 node=127.0.0.1:39239_ek C1582_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:39239/ek, core=collection1, node_name=127.0.0.1:39239_ek}
[junit4] 2> 837480 T4467 C1582 P39239 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:39239/ek/collection1/
[junit4] 2> 837480 T4467 C1582 P39239 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 837481 T4467 C1582 P39239 oasc.SyncStrategy.syncToMe http://127.0.0.1:39239/ek/collection1/ has no replicas
[junit4] 2> 837481 T4467 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:39239/ek/collection1/ shard3
[junit4] 2> 837481 T4467 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
[junit4] 2> 837482 T4423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 837485 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 837488 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 837490 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 837592 T4444 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 837592 T4462 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 837592 T4431 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 837592 T4422 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 837639 T4467 oasc.ZkController.register We are http://127.0.0.1:39239/ek/collection1/ and leader is http://127.0.0.1:39239/ek/collection1/
[junit4] 2> 837639 T4467 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:39239/ek
[junit4] 2> 837639 T4467 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 837639 T4467 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 837640 T4467 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 837641 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 837641 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 837641 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 837641 T4467 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 837642 T4423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 837642 T4423 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:39239/ek",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:39239_ek",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 837646 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 837748 T4444 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 837748 T4462 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 837748 T4431 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 837748 T4422 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 837816 T4400 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 837817 T4400 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 837818 T4400 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53846
[junit4] 2> 837820 T4400 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 837820 T4400 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 837820 T4400 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-005
[junit4] 2> 837821 T4400 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-005/'
[junit4] 2> 837831 T4400 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-005/solr.xml
[junit4] 2> 837838 T4400 oasc.CoreContainer.<init> New CoreContainer 88230085
[junit4] 2> 837839 T4400 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-005/]
[junit4] 2> 837839 T4400 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 837839 T4400 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 837840 T4400 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 837840 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 837840 T4400 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 837840 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 837840 T4400 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 837841 T4400 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 837841 T4400 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 837841 T4400 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 837841 T4400 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 837842 T4400 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 837842 T4400 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52145/solr
[junit4] 2> 837842 T4400 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 837843 T4400 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 837844 T4478 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4877b80c name:ZooKeeperConnection Watcher:127.0.0.1:52145 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 837845 T4400 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 837846 T4400 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 837847 T4480 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2fab1a40 name:ZooKeeperConnection Watcher:127.0.0.1:52145/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 837847 T4400 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 837851 T4400 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 838854 T4400 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53846_ek
[junit4] 2> 838855 T4400 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53846_ek
[junit4] 2> 838865 T4481 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 838866 T4481 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 838867 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 838867 T4481 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 838867 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 838867 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 838870 T4423 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 838870 T4423 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:53846/ek",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:53846_ek",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":null}
[junit4] 2> 838870 T4423 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=3
[junit4] 2> 838871 T4423 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 838872 T4422 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 839023 T4444 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 839023 T4431 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 839023 T4462 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 839023 T4422 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 839023 T4480 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 839867 T4481 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 839868 T4481 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 839868 T4481 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 839868 T4481 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 839869 T4481 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 839870 T4481 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-005/collection1/'
[junit4] 2> 839870 T4481 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-005/collection1/lib/.svn/' to classloader
[junit4] 2> 839870 T4481 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/solr.cloud.FullSolrCloudDistribCmdsTest-4DDA8A25E7338422-001/tempDir-005/collection1/lib/classes/' to classloader
[junit4] 2> 839870 T4481 oasc.SolrResourceLoader.replaceClassLo
[...truncated too long message...]
)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:277)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:306)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 899306 T4422 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 899386 T4594 oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read the data org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:277)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:306)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 899386 T4444 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 899464 T4597 oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read the data org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:277)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:306)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 899541 T4600 oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read the data org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:277)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:306)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 899542 T4498 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 899617 T4603 oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read the data org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:277)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:306)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> NOTE: test params are: codec=Lucene42, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=fr_LU, timezone=Pacific/Tahiti
[junit4] 2> NOTE: Linux 3.8.0-39-generic amd64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=8,threads=1,free=191117432,total=509607936
[junit4] 2> NOTE: All tests run in this JVM: [CursorPagingTest, HdfsCollectionsAPIDistributedZkTest, TestFastOutputStream, CoreMergeIndexesAdminHandlerTest, TestQueryTypes, DeleteReplicaTest, HighlighterTest, DeleteInactiveReplicaTest, TestManagedStopFilterFactory, CollectionsAPIDistributedZkTest, BadCopyFieldTest, TestDistributedGrouping, NumericFieldsTest, SuggestComponentTest, OutputWriterTest, TestQuerySenderListener, TestRTGBase, TestStressLucene, DistributedSuggestComponentTest, TestSolrXml, TestPhraseSuggestions, HdfsChaosMonkeySafeLeaderTest, TestCollectionAPI, TestCoreDiscovery, BasicDistributedZk2Test, TermVectorComponentDistributedTest, TestSolr4Spatial, TestSolrXmlPersistence, TestCloudInspectUtil, TestInfoStreamLogging, TestFoldingMultitermQuery, DOMUtilTest, IndexBasedSpellCheckerTest, IndexSchemaRuntimeFieldTest, AliasIntegrationTest, TestArbitraryIndexDir, TestRangeQuery, TestInitQParser, TestDistributedSearch, ExpressionTest, SSLMigrationTest, TestStressReorder, TestReloadAndDeleteDocs, TestFreeTextSuggestions, OpenExchangeRatesOrgProviderTest, TestSolrXMLSerializer, TestFastWriter, TestDynamicFieldCollectionResource, TestNonNRTOpen, OverseerRolesTest, ZkSolrClientTest, TestDFRSimilarityFactory, TestFieldTypeResource, DocValuesMultiTest, FieldAnalysisRequestHandlerTest, PingRequestHandlerTest, AtomicUpdatesTest, TestRecovery, HdfsDirectoryTest, BasicZkTest, XmlUpdateRequestHandlerTest, TestNonDefinedSimilarityFactory, TestRecoveryHdfs, SuggesterTSTTest, StandardRequestHandlerTest, TestBinaryResponseWriter, SuggesterWFSTTest, TestDistribDocBasedVersion, SolrPluginUtilsTest, TestMultiCoreConfBootstrap, TestZkChroot, TestXIncludeConfig, PluginInfoTest, TestCodecSupport, DocumentAnalysisRequestHandlerTest, NoCacheHeaderTest, TestLazyCores, TestFiltering, NotRequiredUniqueKeyTest, ResourceLoaderTest, TestExceedMaxTermLength, PrimUtilsTest, RangeFacetTest, TestCSVLoader, TestAnalyzedSuggestions, EnumFieldTest, TestCollationField, AnalysisErrorHandlingTest, BJQParserTest, DirectSolrConnectionTest, BlockCacheTest, ShardRoutingCustomTest, TestFuzzyAnalyzedSuggestions, MultiTermTest, SolrCoreCheckLockOnStartupTest, TestLRUCache, OverseerStatusTest, ChaosMonkeySafeLeaderTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, FullSolrCloudDistribCmdsTest]
[junit4] Completed on J1 in 69.05s, 1 test, 1 error <<< FAILURES!
[...truncated 597 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1296: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:920: There were test failures: 403 suites, 1681 tests, 1 error, 37 ignored (16 assumptions)
Total time: 58 minutes 9 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0_05 -XX:-UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0_05) - Build #
10308 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/10308/
Java: 64bit/jdk1.8.0_05 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.cloud.OverseerRolesTest.testDistribSearch
Error Message:
New overseer designate has not become the overseer, expected : 127.0.0.1:50988_qhtkd%2Fbdactual : null
Stack Trace:
java.lang.AssertionError: New overseer designate has not become the overseer, expected : 127.0.0.1:50988_qhtkd%2Fbdactual : null
at __randomizedtesting.SeedInfo.seed([FB7F6F4750AA0BFC:7A99E15F27F56BC0]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.OverseerRolesTest.testOverseerRole(OverseerRolesTest.java:223)
at org.apache.solr.cloud.OverseerRolesTest.doTest(OverseerRolesTest.java:94)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
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.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:360)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:793)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:453)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:360)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11024 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerRolesTest
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/init-core-data-001
[junit4] 2> 406829 T2028 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /qhtkd/bd
[junit4] 2> 406832 T2028 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 406832 T2028 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 406833 T2029 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 406933 T2028 oasc.ZkTestServer.run start zk server on port:36673
[junit4] 2> 406934 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 407074 T2035 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5ca72bb3 name:ZooKeeperConnection Watcher:127.0.0.1:36673 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 407074 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 407075 T2028 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 407079 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 407080 T2037 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7c4dbde4 name:ZooKeeperConnection Watcher:127.0.0.1:36673/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 407080 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 407080 T2028 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 407082 T2028 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 407084 T2028 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 407085 T2028 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 407086 T2028 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 407087 T2028 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 407282 T2028 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 407283 T2028 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 407286 T2028 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 407287 T2028 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 407290 T2028 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 407290 T2028 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 407294 T2028 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 407295 T2028 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 407298 T2028 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 407298 T2028 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 407302 T2028 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 407303 T2028 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 407306 T2028 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 407307 T2028 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 407309 T2028 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 407310 T2028 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 407314 T2028 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 407315 T2028 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 407319 T2028 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 407319 T2028 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 407560 T2028 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 407564 T2028 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57169
[junit4] 2> 407564 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 407565 T2028 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 407565 T2028 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-002
[junit4] 2> 407565 T2028 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-002/'
[junit4] 2> 407579 T2028 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-002/solr.xml
[junit4] 2> 407608 T2028 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-002
[junit4] 2> 407609 T2028 oasc.CoreContainer.<init> New CoreContainer 2117223732
[junit4] 2> 407609 T2028 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-002/]
[junit4] 2> 407610 T2028 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 407610 T2028 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 407611 T2028 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 407611 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 407611 T2028 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 407612 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 407612 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 407612 T2028 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 407613 T2028 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 407614 T2028 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 407614 T2028 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 407614 T2028 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 407615 T2028 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36673/solr
[junit4] 2> 407615 T2028 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 407616 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 407618 T2048 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1778e3b5 name:ZooKeeperConnection Watcher:127.0.0.1:36673 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 407619 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 407623 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 407623 T2050 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@773d8569 name:ZooKeeperConnection Watcher:127.0.0.1:36673/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 407623 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 407624 T2028 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 407627 T2028 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 407629 T2028 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 407631 T2028 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 407633 T2028 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 407635 T2028 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 407636 T2028 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57169_qhtkd%2Fbd
[junit4] 2> 407637 T2028 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57169_qhtkd%2Fbd
[junit4] 2> 407638 T2028 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 407649 T2028 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 407653 T2028 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:57169_qhtkd%2Fbd
[junit4] 2> 407653 T2028 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 407654 T2028 oasc.Overseer.start Overseer (id=91762332294578179-127.0.0.1:57169_qhtkd%2Fbd-n_0000000000) starting
[junit4] 2> 407656 T2028 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 407665 T2052 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 407665 T2028 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 407667 T2028 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 407669 T2028 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 407670 T2051 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 407671 T2028 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-002
[junit4] 2> 407676 T2028 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-002/conf/
[junit4] 2> 407768 T2028 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 407769 T2028 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 407770 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 407771 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 407772 T2055 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5e6349b1 name:ZooKeeperConnection Watcher:127.0.0.1:36673/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 407772 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 407773 T2028 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 407774 T2028 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 408009 T2028 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 408010 T2028 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 408012 T2028 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:38976
[junit4] 2> 408013 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 408013 T2028 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 408013 T2028 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-003
[junit4] 2> 408013 T2028 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-003/'
[junit4] 2> 408025 T2028 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-003/solr.xml
[junit4] 2> 408034 T2028 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-003
[junit4] 2> 408035 T2028 oasc.CoreContainer.<init> New CoreContainer 1778443622
[junit4] 2> 408035 T2028 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-003/]
[junit4] 2> 408036 T2028 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 408036 T2028 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 408036 T2028 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 408036 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 408037 T2028 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 408037 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 408037 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 408037 T2028 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 408037 T2028 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 408038 T2028 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 408038 T2028 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 408038 T2028 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 408039 T2028 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36673/solr
[junit4] 2> 408039 T2028 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 408039 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 408041 T2066 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2e344cc8 name:ZooKeeperConnection Watcher:127.0.0.1:36673 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 408042 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 408043 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 408045 T2068 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6e2b278d name:ZooKeeperConnection Watcher:127.0.0.1:36673/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 408045 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 408049 T2028 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 409051 T2028 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38976_qhtkd%2Fbd
[junit4] 2> 409052 T2028 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:38976_qhtkd%2Fbd
[junit4] 2> 409057 T2028 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-003
[junit4] 2> 409063 T2028 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-003/conf/
[junit4] 2> 409187 T2028 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 409188 T2028 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 409189 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 409419 T2028 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 409420 T2028 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 409421 T2028 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50988
[junit4] 2> 409422 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 409422 T2028 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 409423 T2028 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-004
[junit4] 2> 409423 T2028 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-004/'
[junit4] 2> 409435 T2028 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-004/solr.xml
[junit4] 2> 409449 T2028 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-004
[junit4] 2> 409450 T2028 oasc.CoreContainer.<init> New CoreContainer 1874439017
[junit4] 2> 409450 T2028 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-004/]
[junit4] 2> 409451 T2028 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 409451 T2028 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 409452 T2028 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 409452 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 409452 T2028 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 409453 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 409453 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 409453 T2028 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 409454 T2028 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 409454 T2028 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 409455 T2028 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 409455 T2028 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 409456 T2028 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36673/solr
[junit4] 2> 409456 T2028 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 409457 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 409459 T2080 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@34305377 name:ZooKeeperConnection Watcher:127.0.0.1:36673 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 409460 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 409462 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 409463 T2082 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@862ff77 name:ZooKeeperConnection Watcher:127.0.0.1:36673/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 409463 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 409468 T2028 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 410469 T2028 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50988_qhtkd%2Fbd
[junit4] 2> 410471 T2028 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50988_qhtkd%2Fbd
[junit4] 2> 410478 T2028 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-004
[junit4] 2> 410491 T2028 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-004/conf/
[junit4] 2> 410610 T2028 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 410611 T2028 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 410611 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 410836 T2028 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 410836 T2028 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 410839 T2028 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:44341
[junit4] 2> 410839 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 410840 T2028 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 410840 T2028 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-005
[junit4] 2> 410840 T2028 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-005/'
[junit4] 2> 410852 T2028 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-005/solr.xml
[junit4] 2> 410860 T2028 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-005
[junit4] 2> 410861 T2028 oasc.CoreContainer.<init> New CoreContainer 546432563
[junit4] 2> 410861 T2028 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-005/]
[junit4] 2> 410862 T2028 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 410862 T2028 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 410862 T2028 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 410862 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 410863 T2028 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 410863 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 410863 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 410863 T2028 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 410863 T2028 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 410864 T2028 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 410864 T2028 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 410864 T2028 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 410865 T2028 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36673/solr
[junit4] 2> 410865 T2028 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 410865 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 410867 T2094 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@43449d2c name:ZooKeeperConnection Watcher:127.0.0.1:36673 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 410867 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 410870 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 410871 T2096 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2d2f4a51 name:ZooKeeperConnection Watcher:127.0.0.1:36673/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 410872 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 410880 T2028 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 411883 T2028 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44341_qhtkd%2Fbd
[junit4] 2> 411883 T2028 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44341_qhtkd%2Fbd
[junit4] 2> 411888 T2028 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-005
[junit4] 2> 411892 T2028 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-005/conf/
[junit4] 2> 411986 T2028 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 411987 T2028 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 411987 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 412231 T2028 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 4
[junit4] 2> 412232 T2028 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 412234 T2028 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:38300
[junit4] 2> 412235 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 412235 T2028 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 412236 T2028 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-006
[junit4] 2> 412236 T2028 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-006/'
[junit4] 2> 412248 T2028 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-006/solr.xml
[junit4] 2> 412257 T2028 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-006
[junit4] 2> 412258 T2028 oasc.CoreContainer.<init> New CoreContainer 107841679
[junit4] 2> 412258 T2028 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-006/]
[junit4] 2> 412259 T2028 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 412259 T2028 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 412259 T2028 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 412259 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 412259 T2028 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 412260 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 412260 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 412260 T2028 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 412260 T2028 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 412261 T2028 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 412261 T2028 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 412261 T2028 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 412262 T2028 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36673/solr
[junit4] 2> 412262 T2028 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 412263 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 412265 T2108 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@169e4e7a name:ZooKeeperConnection Watcher:127.0.0.1:36673 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 412266 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 412267 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 412268 T2110 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@673b3e7a name:ZooKeeperConnection Watcher:127.0.0.1:36673/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 412268 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 412272 T2028 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 413273 T2028 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38300_qhtkd%2Fbd
[junit4] 2> 413274 T2028 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:38300_qhtkd%2Fbd
[junit4] 2> 413284 T2028 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-006
[junit4] 2> 413290 T2028 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-006/conf/
[junit4] 2> 413406 T2028 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 413407 T2028 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 413408 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 413634 T2028 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 5
[junit4] 2> 413635 T2028 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 413637 T2028 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49093
[junit4] 2> 413638 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 413638 T2028 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 413638 T2028 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007
[junit4] 2> 413639 T2028 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/'
[junit4] 2> 413650 T2028 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/solr.xml
[junit4] 2> 413686 T2028 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007
[junit4] 2> 413686 T2028 oasc.CoreContainer.<init> New CoreContainer 909319516
[junit4] 2> 413686 T2028 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/]
[junit4] 2> 413687 T2028 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 413687 T2028 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 413688 T2028 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 413688 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 413688 T2028 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 413689 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 413689 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 413690 T2028 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 413690 T2028 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 413691 T2028 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 413691 T2028 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 413691 T2028 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 413692 T2028 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36673/solr
[junit4] 2> 413692 T2028 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 413693 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 413695 T2122 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7be4341 name:ZooKeeperConnection Watcher:127.0.0.1:36673 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 413695 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 413698 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 413699 T2124 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3adc3fcf name:ZooKeeperConnection Watcher:127.0.0.1:36673/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 413700 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 413708 T2028 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 414712 T2028 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49093_qhtkd%2Fbd
[junit4] 2> 414714 T2028 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49093_qhtkd%2Fbd
[junit4] 2> 414725 T2028 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007
[junit4] 2> 414738 T2028 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/conf/
[junit4] 2> 414865 T2028 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 414866 T2028 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 414867 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 415087 T2028 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 6
[junit4] 2> 415087 T2028 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 415089 T2028 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:44871
[junit4] 2> 415090 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 415090 T2028 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 415090 T2028 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-008
[junit4] 2> 415091 T2028 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-008/'
[junit4] 2> 415106 T2028 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-008/solr.xml
[junit4] 2> 415115 T2028 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-008
[junit4] 2> 415115 T2028 oasc.CoreContainer.<init> New CoreContainer 1394898741
[junit4] 2> 415115 T2028 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-008/]
[junit4] 2> 415116 T2028 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 415116 T2028 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 415116 T2028 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 415116 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 415117 T2028 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 415117 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 415117 T2028 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 415117 T2028 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 415117 T2028 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 415118 T2028 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 415118 T2028 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 415118 T2028 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 415119 T2028 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36673/solr
[junit4] 2> 415119 T2028 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 415120 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 415122 T2136 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@590dabca name:ZooKeeperConnection Watcher:127.0.0.1:36673 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 415124 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 415126 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 415127 T2138 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@47f11683 name:ZooKeeperConnection Watcher:127.0.0.1:36673/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 415127 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 415134 T2028 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 416136 T2028 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44871_qhtkd%2Fbd
[junit4] 2> 416138 T2028 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44871_qhtkd%2Fbd
[junit4] 2> 416148 T2028 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-008
[junit4] 2> 416158 T2028 oasc.CorePropertiesLocator.discoverUnder Found core conf in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-008/conf/
[junit4] 2> 416247 T2028 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 416248 T2028 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 416249 T2028 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 416251 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 416253 T2141 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@27e75bd7 name:ZooKeeperConnection Watcher:127.0.0.1:36673/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 416254 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 416255 T2028 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 416259 T2028 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 416260 T2143 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@578a0dc1 name:ZooKeeperConnection Watcher:127.0.0.1:36673/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 416260 T2028 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 416262 T2028 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 416270 T2044 oasha.CollectionsHandler.handleCreateAction Creating Collection : replicationFactor=2&maxShardsPerNode=2&name=testOverseerQuit&action=CREATE&numShards=4&wt=javabin&version=2
[junit4] 2> 416272 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416274 T2052 oasc.OverseerCollectionProcessor.run Overseer Collection Processor: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"testOverseerQuit",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "numShards":"4",
[junit4] 2> "maxShardsPerNode":"2"}
[junit4] 2> 416274 T2052 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : createcollection , {
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"testOverseerQuit",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "numShards":"4",
[junit4] 2> "maxShardsPerNode":"2"}
[junit4] 2> 416277 T2052 oasc.OverseerCollectionProcessor.createConfNode Only one config set found in zk - using it:conf1
[junit4] 2> 416278 T2052 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/testOverseerQuit
[junit4] 2> 416278 T2052 oascc.SolrZkClient.makePath makePath: /collections/testOverseerQuit
[junit4] 2> 416283 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416285 T2051 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 416286 T2051 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: testOverseerQuit
[junit4] 2> 416286 T2051 oasc.Overseer$ClusterStateUpdater.createCollection Create collection testOverseerQuit with shards [shard1, shard2, shard3, shard4]
[junit4] 2> 416289 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416290 T2050 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416290 T2124 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416291 T2143 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416291 T2141 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416290 T2110 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416290 T2138 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416290 T2096 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416290 T2055 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416290 T2068 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416290 T2082 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416383 T2052 oasc.OverseerCollectionProcessor.createCollection Creating SolrCores for new collection, shardNames [shard1, shard2, shard3, shard4] , replicationFactor : 2
[junit4] 2> 416383 T2052 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerQuit_shard1_replica1 as part of slice shard1 of collection testOverseerQuit on 127.0.0.1:49093_qhtkd%2Fbd
[junit4] 2> 416384 T2052 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerQuit_shard1_replica2 as part of slice shard1 of collection testOverseerQuit on 127.0.0.1:38300_qhtkd%2Fbd
[junit4] 2> 416386 T2052 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerQuit_shard2_replica1 as part of slice shard2 of collection testOverseerQuit on 127.0.0.1:44341_qhtkd%2Fbd
[junit4] 2> 416388 T2119 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=testOverseerQuit_shard1_replica1&action=CREATE&numShards=4&collection=testOverseerQuit&shard=shard1&wt=javabin&version=2
[junit4] 2> 416388 T2104 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=testOverseerQuit_shard1_replica2&action=CREATE&numShards=4&collection=testOverseerQuit&shard=shard1&wt=javabin&version=2
[junit4] 2> 416388 T2052 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerQuit_shard2_replica2 as part of slice shard2 of collection testOverseerQuit on 127.0.0.1:44871_qhtkd%2Fbd
[junit4] 2> 416390 T2119 oasc.ZkController.publish publishing core=testOverseerQuit_shard1_replica1 state=down collection=testOverseerQuit
[junit4] 2> 416390 T2104 oasc.ZkController.publish publishing core=testOverseerQuit_shard1_replica2 state=down collection=testOverseerQuit
[junit4] 2> 416389 T2092 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=testOverseerQuit_shard2_replica1&action=CREATE&numShards=4&collection=testOverseerQuit&shard=shard2&wt=javabin&version=2
[junit4] 2> 416391 T2052 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerQuit_shard3_replica1 as part of slice shard3 of collection testOverseerQuit on 127.0.0.1:50988_qhtkd%2Fbd
[junit4] 2> 416392 T2134 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=testOverseerQuit_shard2_replica2&action=CREATE&numShards=4&collection=testOverseerQuit&shard=shard2&wt=javabin&version=2
[junit4] 2> 416396 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416396 T2119 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 416397 T2052 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerQuit_shard3_replica2 as part of slice shard3 of collection testOverseerQuit on 127.0.0.1:57169_qhtkd%2Fbd
[junit4] 2> 416396 T2104 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 416397 T2134 oasc.ZkController.publish publishing core=testOverseerQuit_shard2_replica2 state=down collection=testOverseerQuit
[junit4] 2> 416398 T2078 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=testOverseerQuit_shard3_replica1&action=CREATE&numShards=4&collection=testOverseerQuit&shard=shard3&wt=javabin&version=2
[junit4] 2> 416397 T2092 oasc.ZkController.publish publishing core=testOverseerQuit_shard2_replica1 state=down collection=testOverseerQuit
[junit4] 2> 416399 T2046 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=testOverseerQuit_shard3_replica2&action=CREATE&numShards=4&collection=testOverseerQuit&shard=shard3&wt=javabin&version=2
[junit4] 2> 416399 T2134 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 416399 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416398 T2051 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 416401 T2078 oasc.ZkController.publish publishing core=testOverseerQuit_shard3_replica1 state=down collection=testOverseerQuit
[junit4] 2> 416398 T2052 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerQuit_shard4_replica1 as part of slice shard4 of collection testOverseerQuit on 127.0.0.1:38976_qhtkd%2Fbd
[junit4] 2> 416402 T2051 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:49093/qhtkd/bd",
[junit4] 2> "core":"testOverseerQuit_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:49093_qhtkd%2Fbd",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testOverseerQuit",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 416401 T2046 oasc.ZkController.publish publishing core=testOverseerQuit_shard3_replica2 state=down collection=testOverseerQuit
[junit4] 2> 416402 T2052 oasc.OverseerCollectionProcessor.createCollection Creating shard testOverseerQuit_shard4_replica2 as part of slice shard4 of collection testOverseerQuit on 127.0.0.1:49093_qhtkd%2Fbd
[junit4] 2> 416400 T2092 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 416405 T2051 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38300/qhtkd/bd",
[junit4] 2> "core":"testOverseerQuit_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38300_qhtkd%2Fbd",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testOverseerQuit",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 416404 T2063 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=testOverseerQuit_shard4_replica1&action=CREATE&numShards=4&collection=testOverseerQuit&shard=shard4&wt=javabin&version=2
[junit4] 2> 416403 T2046 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 416409 T2120 oasha.CoreAdminHandler.handleCreateAction core create command qt=/admin/cores&collection.configName=conf1&name=testOverseerQuit_shard4_replica2&action=CREATE&numShards=4&collection=testOverseerQuit&shard=shard4&wt=javabin&version=2
[junit4] 2> 416402 T2078 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 416410 T2063 oasc.ZkController.publish publishing core=testOverseerQuit_shard4_replica1 state=down collection=testOverseerQuit
[junit4] 2> 416412 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416413 T2063 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 416413 T2120 oasc.ZkController.publish publishing core=testOverseerQuit_shard4_replica2 state=down collection=testOverseerQuit
[junit4] 2> 416414 T2051 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:44871/qhtkd/bd",
[junit4] 2> "core":"testOverseerQuit_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44871_qhtkd%2Fbd",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testOverseerQuit",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 416414 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416414 T2120 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 416418 T2051 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:44341/qhtkd/bd",
[junit4] 2> "core":"testOverseerQuit_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44341_qhtkd%2Fbd",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testOverseerQuit",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 416420 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416420 T2051 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50988/qhtkd/bd",
[junit4] 2> "core":"testOverseerQuit_shard3_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:50988_qhtkd%2Fbd",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"testOverseerQuit",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 416422 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416422 T2051 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57169/qhtkd/bd",
[junit4] 2> "core":"testOverseerQuit_shard3_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:57169_qhtkd%2Fbd",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"testOverseerQuit",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 416424 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416424 T2051 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38976/qhtkd/bd",
[junit4] 2> "core":"testOverseerQuit_shard4_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38976_qhtkd%2Fbd",
[junit4] 2> "shard":"shard4",
[junit4] 2> "collection":"testOverseerQuit",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 416425 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416426 T2051 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:49093/qhtkd/bd",
[junit4] 2> "core":"testOverseerQuit_shard4_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:49093_qhtkd%2Fbd",
[junit4] 2> "shard":"shard4",
[junit4] 2> "collection":"testOverseerQuit",
[junit4] 2> "numShards":"4",
[junit4] 2> "core_node_name":null}
[junit4] 2> 416427 T2050 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 416529 T2068 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416530 T2143 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416530 T2055 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416529 T2138 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416530 T2141 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416529 T2110 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416529 T2124 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416529 T2082 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416529 T2050 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 416529 T2096 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 7)
[junit4] 2> 417397 T2119 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerQuit_shard1_replica1
[junit4] 2> 417398 T2104 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerQuit_shard1_replica2
[junit4] 2> 417398 T2119 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerQuit
[junit4] 2> 417398 T2104 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerQuit
[junit4] 2> 417398 T2119 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 417399 T2119 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerQuit
[junit4] 2> 417399 T2104 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 417399 T2104 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerQuit
[junit4] 2> 417400 T2119 oascc.ZkStateReader.readConfigName path=/collections/testOverseerQuit configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 417400 T2119 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/testOverseerQuit_shard1_replica1/'
[junit4] 2> 417400 T2104 oascc.ZkStateReader.readConfigName path=/collections/testOverseerQuit configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 417400 T2104 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-006/testOverseerQuit_shard1_replica2/'
[junit4] 2> 417400 T2134 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerQuit_shard2_replica2
[junit4] 2> 417401 T2134 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerQuit
[junit4] 2> 417401 T2134 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 417402 T2134 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerQuit
[junit4] 2> 417403 T2134 oascc.ZkStateReader.readConfigName path=/collections/testOverseerQuit configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 417403 T2134 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-008/testOverseerQuit_shard2_replica2/'
[junit4] 2> 417408 T2092 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerQuit_shard2_replica1
[junit4] 2> 417409 T2092 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerQuit
[junit4] 2> 417409 T2046 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerQuit_shard3_replica2
[junit4] 2> 417409 T2092 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 417410 T2092 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerQuit
[junit4] 2> 417410 T2046 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerQuit
[junit4] 2> 417410 T2078 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerQuit_shard3_replica1
[junit4] 2> 417410 T2046 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 417411 T2046 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerQuit
[junit4] 2> 417411 T2078 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerQuit
[junit4] 2> 417411 T2092 oascc.ZkStateReader.readConfigName path=/collections/testOverseerQuit configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 417412 T2092 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-005/testOverseerQuit_shard2_replica1/'
[junit4] 2> 417412 T2078 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 417412 T2046 oascc.ZkStateReader.readConfigName path=/collections/testOverseerQuit configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 417412 T2078 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerQuit
[junit4] 2> 417413 T2046 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-002/testOverseerQuit_shard3_replica2/'
[junit4] 2> 417413 T2063 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerQuit_shard4_replica1
[junit4] 2> 417414 T2063 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerQuit
[junit4] 2> 417415 T2078 oascc.ZkStateReader.readConfigName path=/collections/testOverseerQuit configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 417415 T2120 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for testOverseerQuit_shard4_replica2
[junit4] 2> 417415 T2063 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 417416 T2078 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-004/testOverseerQuit_shard3_replica1/'
[junit4] 2> 417417 T2120 oasc.ZkController.createCollectionZkNode Check for collection zkNode:testOverseerQuit
[junit4] 2> 417416 T2063 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerQuit
[junit4] 2> 417418 T2120 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 417419 T2120 oascc.ZkStateReader.readConfigName Load collection config from:/collections/testOverseerQuit
[junit4] 2> 417419 T2063 oascc.ZkStateReader.readConfigName path=/collections/testOverseerQuit configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 417419 T2063 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-003/testOverseerQuit_shard4_replica1/'
[junit4] 2> 417421 T2120 oascc.ZkStateReader.readConfigName path=/collections/testOverseerQuit configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 417421 T2120 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/testOverseerQuit_shard4_replica2/'
[junit4] 2> 417437 T2119 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 417438 T2134 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 417452 T2104 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 417454 T2119 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 417456 T2134 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 417458 T2119 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 417460 T2134 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 417461 T2046 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 417486 T2092 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 417494 T2119 oass.IndexSchema.readSchema [testOverseerQuit_shard1_replica1] Schema name=test
[junit4] 2> 417497 T2104 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 417495 T2134 oass.IndexSchema.readSchema [testOverseerQuit_shard2_replica2] Schema name=test
[junit4] 2> 417495 T2046 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 417501 T2104 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 417502 T2120 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 417504 T2092 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 417505 T2092 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 417512 T2104 oass.IndexSchema.readSchema [testOverseerQuit_shard1_replica2] Schema name=test
[junit4] 2> 417513 T2092 oass.IndexSchema.readSchema [testOverseerQuit_shard2_replica1] Schema name=test
[junit4] 2> 417516 T2046 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 417519 T2078 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 417529 T2046 oass.IndexSchema.readSchema [testOverseerQuit_shard3_replica2] Schema name=test
[junit4] 2> 417534 T2063 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 417534 T2120 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 417539 T2120 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 417567 T2120 oass.IndexSchema.readSchema [testOverseerQuit_shard4_replica2] Schema name=test
[junit4] 2> 417569 T2078 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 417572 T2078 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 417582 T2078 oass.IndexSchema.readSchema [testOverseerQuit_shard3_replica1] Schema name=test
[junit4] 2> 417595 T2063 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 417609 T2063 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 417617 T2063 oass.IndexSchema.readSchema [testOverseerQuit_shard4_replica1] Schema name=test
[junit4] 2> 417831 T2119 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 417851 T2119 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 417853 T2119 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 417868 T2092 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 417874 T2092 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 417876 T2092 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 417877 T2046 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 417877 T2134 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 417882 T2104 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 417883 T2119 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 417884 T2134 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 417884 T2046 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 417886 T2119 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 417886 T2134 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 417887 T2046 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 417887 T2104 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 417890 T2104 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 417890 T2119 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 417890 T2092 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 417893 T2119 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 417896 T2119 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 417896 T2119 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 417897 T2092 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 417897 T2119 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 417898 T2119 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 417898 T2119 oasc.CoreContainer.create Creating SolrCore 'testOverseerQuit_shard1_replica1' using configuration from collection testOverseerQuit
[junit4] 2> 417898 T2119 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 417899 T2119 oasc.SolrCore.<init> [testOverseerQuit_shard1_replica1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/testOverseerQuit_shard1_replica1/, dataDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/testOverseerQuit_shard1_replica1/data/
[junit4] 2> 417900 T2119 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@21d8e1f0
[junit4] 2> 417901 T2046 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 417901 T2134 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 417904 T2046 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 417904 T2063 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 417905 T2119 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/testOverseerQuit_shard1_replica1/data
[junit4] 2> 417905 T2119 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/testOverseerQuit_shard1_replica1/data/index/
[junit4] 2> 417906 T2134 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 417906 T2092 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 417906 T2119 oasc.SolrCore.initIndex WARN [testOverseerQuit_shard1_replica1] Solr index directory '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/testOverseerQuit_shard1_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 417907 T2092 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 417907 T2092 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 417908 T2092 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 417907 T2119 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-007/testOverseerQuit_shard1_replica1/data/index
[junit4] 2> 417909 T2119 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.43848503359643987]
[junit4] 2> 417909 T2092 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 417910 T2134 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 417910 T2092 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 417910 T2063 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 417911 T2134 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 417912 T2134 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 417912 T2134 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 417913 T2120 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 417913 T2104 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 417911 T2092
[...truncated too long message...]
nt.makePath makePath: /collections/testOverseerQuit/leaders/shard3
[junit4] 2> 445697 T2068 oasc.CachingDirectoryFactory.closeCacheValue looking to close ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-003/testOverseerCol_shard4_replica1/data/index [CachedDir<<refCount=0;path=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-003/testOverseerCol_shard4_replica1/data/index;done=false>>]
[junit4] 2> 445698 T2082 oasc.SolrException.log ERROR There was a problem trying to register as the leader:org.apache.solr.common.SolrException: Could not register as the leader because creating the ephemeral registration node in ZooKeeper failed
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:135)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:290)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:142)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:110)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:303)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:209)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:206)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:206)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:417)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:386)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:373)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase$1.execute(ElectionContext.java:127)
[junit4] 2> at org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:31)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:122)
[junit4] 2> ... 7 more
[junit4] 2>
[junit4] 2> 445698 T2068 oasc.CachingDirectoryFactory.close Closing directory: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001/tempDir-003/testOverseerCol_shard4_replica1/data/index
[junit4] 2> 445699 T2082 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 445699 T2068 oascc.SolrZkClient.makePath makePath: /collections/testOverseerCol/leaders/shard4
[junit4] 2> 445700 T2068 oasc.SolrException.log ERROR There was a problem trying to register as the leader:org.apache.solr.common.SolrException: Could not register as the leader because creating the ephemeral registration node in ZooKeeper failed
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:135)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:290)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:142)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:110)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:303)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:209)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:206)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:206)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:417)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:386)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:373)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase$1.execute(ElectionContext.java:127)
[junit4] 2> at org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:31)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:122)
[junit4] 2> ... 7 more
[junit4] 2>
[junit4] 2> 445701 T2068 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solr.cloud.OverseerRolesTest-FB7F6F4750AA0BFC-001
[junit4] 2> NOTE: test params are: codec=Lucene46: {}, docValues:{}, sim=DefaultSimilarity, locale=de, timezone=Pacific/Fiji
[junit4] 2> NOTE: Linux 3.8.0-39-generic amd64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=8,threads=1,free=228305216,total=532676608
[junit4] 2> NOTE: All tests run in this JVM: [SolrRequestParserTest, TestIndexingPerformance, TestHashPartitioner, DistributedSuggestComponentTest, TestCSVResponseWriter, FullSolrCloudDistribCmdsTest, FieldFacetExtrasTest, TestQueryTypes, BadIndexSchemaTest, TestRandomFaceting, TestExceedMaxTermLength, ExpressionTest, BlockCacheTest, TestFuzzyAnalyzedSuggestions, EnumFieldTest, HighlighterTest, StressHdfsTest, TestDFRSimilarityFactory, TestCSVLoader, TestBinaryField, TestAnalyzedSuggestions, TestSchemaSimilarityResource, ZkSolrClientTest, TestStressReorder, TestZkChroot, DateMathParserTest, BadCopyFieldTest, SSLMigrationTest, MinimalSchemaTest, TestFreeTextSuggestions, SignatureUpdateProcessorFactoryTest, NotRequiredUniqueKeyTest, DirectSolrConnectionTest, TestNonDefinedSimilarityFactory, TestModifyConfFiles, TestUpdate, TermVectorComponentDistributedTest, PrimUtilsTest, AnalysisErrorHandlingTest, TestStressRecovery, TestDistributedSearch, IndexSchemaRuntimeFieldTest, TestComponentsName, SuggesterTSTTest, TestFieldTypeResource, DefaultValueUpdateProcessorTest, StandardRequestHandlerTest, DocumentAnalysisRequestHandlerTest, TestInitQParser, TestFastWriter, TestDynamicFieldCollectionResource, OpenCloseCoreStressTest, IndexBasedSpellCheckerTest, TestRecoveryHdfs, LeaderElectionIntegrationTest, SpellCheckComponentTest, DebugComponentTest, UniqFieldsUpdateProcessorFactoryTest, CurrencyFieldXmlFileTest, HighlighterConfigTest, TestRecovery, HdfsCollectionsAPIDistributedZkTest, TestCollationField, RangeFacetTest, TestIBSimilarityFactory, CachingDirectoryFactoryTest, TestSolrXMLSerializer, FieldAnalysisRequestHandlerTest, PingRequestHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, RankQueryTest, OverseerRolesTest]
[junit4] Completed on J1 in 39.61s, 1 test, 1 failure <<< FAILURES!
[...truncated 788 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:45: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:496: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1296: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:920: There were test failures: 403 suites, 1681 tests, 1 failure, 37 ignored (16 assumptions)
Total time: 56 minutes 31 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0_05 -XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure