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/01/15 08:28:16 UTC
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.6.0_45) - Build # 8998
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/8998/
Java: 32bit/jdk1.6.0_45 -client -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testDistribSearch
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([DE877686E923DBBC:5F61F89E9E7CBB80]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertFalse(Assert.java:68)
at org.junit.Assert.assertFalse(Assert.java:79)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.deleteCollectionWithDownNodes(CollectionsAPIDistributedZkTest.java:390)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.doTest(CollectionsAPIDistributedZkTest.java:203)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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 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:70)
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:358)
at java.lang.Thread.run(Thread.java:662)
Build Log:
[...truncated 9805 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> 580322 T2310 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 580325 T2310 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-CollectionsAPIDistributedZkTest-1389769792585
[junit4] 2> 580326 T2310 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 580326 T2311 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 580426 T2310 oasc.ZkTestServer.run start zk server on port:56576
[junit4] 2> 580428 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 580438 T2317 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@169e55b name:ZooKeeperConnection Watcher:127.0.0.1:56576 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 580438 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 580439 T2310 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 580444 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 580448 T2319 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1163e7e name:ZooKeeperConnection Watcher:127.0.0.1:56576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 580448 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 580449 T2310 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 580453 T2310 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 580458 T2310 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 580461 T2310 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 580465 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 580466 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 580472 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 580473 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 580477 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 580478 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 580482 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 580482 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 580486 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 580487 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 580491 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 580491 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 580495 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 580496 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 580500 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 580500 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 580504 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 580504 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 580509 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 580509 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 580513 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 580514 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> Using old style solr.xml
[junit4] 2> 580527 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 580528 T2321 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d4fb58 name:ZooKeeperConnection Watcher:127.0.0.1:56576 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 580528 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 580529 T2310 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 580533 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 580534 T2323 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@168edfc name:ZooKeeperConnection Watcher:127.0.0.1:56576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 580534 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 580535 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf2/solrconfig.xml
[junit4] 2> 580536 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.xml
[junit4] 2> 580541 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
[junit4] 2> 580542 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf2/schema.xml
[junit4] 2> 580547 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 580547 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 580552 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
[junit4] 2> 580552 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf2/stopwords.txt
[junit4] 2> 580557 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
[junit4] 2> 580557 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf2/protwords.txt
[junit4] 2> 580561 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
[junit4] 2> 580562 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf2/currency.xml
[junit4] 2> 580566 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
[junit4] 2> 580566 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf2/open-exchange-rates.json
[junit4] 2> 580570 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 580570 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
[junit4] 2> 580574 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
[junit4] 2> 580575 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf2/old_synonyms.txt
[junit4] 2> 580579 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
[junit4] 2> 580579 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf2/synonyms.txt
[junit4] 2> 580583 T2310 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
[junit4] 2> 580584 T2310 oascc.SolrZkClient.makePath makePath: /configs/conf2/elevate.xml
[junit4] 2> 580679 T2310 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 580682 T2310 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:43128
[junit4] 2> 580683 T2310 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 580683 T2310 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 580684 T2310 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1389769792849
[junit4] 2> 580684 T2310 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1389769792849/'
[junit4] 2> 580697 T2310 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1389769792849/solr.xml
[junit4] 2> 580741 T2310 oasc.CoreContainer.<init> New CoreContainer 19304103
[junit4] 2> 580742 T2310 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-controljetty-1389769792849/]
[junit4] 2> 580743 T2310 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 580744 T2310 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 580744 T2310 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 580744 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 580745 T2310 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 580745 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 580745 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 580746 T2310 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 580746 T2310 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 580748 T2310 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 580748 T2310 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 580749 T2310 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 580749 T2310 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56576/solr
[junit4] 2> 580750 T2310 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 580751 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 580752 T2334 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5f82d7 name:ZooKeeperConnection Watcher:127.0.0.1:56576 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 580752 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 580759 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 580760 T2336 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@130cc36 name:ZooKeeperConnection Watcher:127.0.0.1:56576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 580760 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 580763 T2310 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 580768 T2310 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 580774 T2310 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 580778 T2310 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43128_
[junit4] 2> 580780 T2310 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:43128_
[junit4] 2> 580786 T2310 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 580791 T2310 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 580798 T2310 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 580803 T2310 oasc.Overseer.start Overseer (id=91079953126981637-127.0.0.1:43128_-n_0000000000) starting
[junit4] 2> 580810 T2310 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 580817 T2338 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 580819 T2310 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 580824 T2310 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 580827 T2310 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 580833 T2337 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 580835 T2310 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 580836 T2310 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 580837 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 580838 T2341 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16ca4ea name:ZooKeeperConnection Watcher:127.0.0.1:56576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 580838 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 580841 T2310 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 580845 T2310 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 580921 T2310 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 580924 T2310 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:44511
[junit4] 2> 580924 T2310 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 580925 T2310 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 580925 T2310 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104
[junit4] 2> 580926 T2310 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/'
[junit4] 2> 580939 T2310 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/solr.xml
[junit4] 2> 580993 T2310 oasc.CoreContainer.<init> New CoreContainer 31195707
[junit4] 2> 580994 T2310 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/]
[junit4] 2> 580995 T2310 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 580995 T2310 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 580996 T2310 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 580996 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 580996 T2310 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 580997 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 580997 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 580998 T2310 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 580998 T2310 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 581000 T2310 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 581000 T2310 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 581001 T2310 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 581001 T2310 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56576/solr
[junit4] 2> 581002 T2310 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 581002 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 581005 T2352 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@116a5c8 name:ZooKeeperConnection Watcher:127.0.0.1:56576 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 581005 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 581008 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 581009 T2354 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@22c1c5 name:ZooKeeperConnection Watcher:127.0.0.1:56576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 581010 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 581017 T2310 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 582024 T2310 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44511_
[junit4] 2> 582027 T2310 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44511_
[junit4] 2> 582036 T2336 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 582038 T2341 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 582038 T2354 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 582054 T2310 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 582055 T2310 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 582136 T2310 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 582140 T2310 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59829
[junit4] 2> 582141 T2310 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 582142 T2310 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 582143 T2310 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315
[junit4] 2> 582144 T2310 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/'
[junit4] 2> 582165 T2310 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/solr.xml
[junit4] 2> 582212 T2310 oasc.CoreContainer.<init> New CoreContainer 30715990
[junit4] 2> 582212 T2310 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/]
[junit4] 2> 582214 T2310 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 582214 T2310 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 582214 T2310 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 582215 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 582215 T2310 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 582216 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 582216 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 582216 T2310 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 582217 T2310 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 582219 T2310 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 582219 T2310 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 582220 T2310 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 582220 T2310 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56576/solr
[junit4] 2> 582220 T2310 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 582221 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 582224 T2366 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@850919 name:ZooKeeperConnection Watcher:127.0.0.1:56576 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 582224 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 582230 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 582233 T2368 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19d91bd name:ZooKeeperConnection Watcher:127.0.0.1:56576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 582234 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 582242 T2310 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 583252 T2310 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59829_
[junit4] 2> 583254 T2310 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59829_
[junit4] 2> 583262 T2341 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 583263 T2336 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 583264 T2354 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 583264 T2368 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 583274 T2310 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 583275 T2310 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 583382 T2310 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 583385 T2310 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46069
[junit4] 2> 583386 T2310 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 583386 T2310 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 583387 T2310 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534
[junit4] 2> 583387 T2310 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/'
[junit4] 2> 583402 T2310 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/solr.xml
[junit4] 2> 583449 T2310 oasc.CoreContainer.<init> New CoreContainer 12919765
[junit4] 2> 583450 T2310 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/]
[junit4] 2> 583452 T2310 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 583452 T2310 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 583452 T2310 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 583453 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 583453 T2310 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 583453 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 583454 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 583454 T2310 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 583455 T2310 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 583457 T2310 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 583457 T2310 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 583457 T2310 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 583458 T2310 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56576/solr
[junit4] 2> 583458 T2310 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 583459 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 583461 T2380 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b3b1de name:ZooKeeperConnection Watcher:127.0.0.1:56576 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 583461 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 583465 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 583466 T2382 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12e2da9 name:ZooKeeperConnection Watcher:127.0.0.1:56576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 583466 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 583473 T2310 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 584479 T2310 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46069_
[junit4] 2> 584480 T2310 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46069_
[junit4] 2> 584486 T2354 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 584486 T2336 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 584486 T2368 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 584487 T2341 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 584487 T2382 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 584495 T2310 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 584495 T2310 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 584563 T2310 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 584565 T2310 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:41774
[junit4] 2> 584566 T2310 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 584566 T2310 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 584567 T2310 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755
[junit4] 2> 584567 T2310 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/'
[junit4] 2> 584580 T2310 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/solr.xml
[junit4] 2> 584657 T2310 oasc.CoreContainer.<init> New CoreContainer 23234722
[junit4] 2> 584657 T2310 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/]
[junit4] 2> 584659 T2310 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 584659 T2310 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 584659 T2310 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 584660 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 584660 T2310 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 584661 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 584661 T2310 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 584661 T2310 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 584662 T2310 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 584664 T2310 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 584664 T2310 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 584665 T2310 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 584665 T2310 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56576/solr
[junit4] 2> 584665 T2310 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 584666 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 584668 T2394 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f882f7 name:ZooKeeperConnection Watcher:127.0.0.1:56576 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 584668 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 584673 T2310 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 584675 T2396 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7b3860 name:ZooKeeperConnection Watcher:127.0.0.1:56576/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 584675 T2310 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 584682 T2310 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 585686 T2310 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41774_
[junit4] 2> 585689 T2310 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41774_
[junit4] 2> 585695 T2354 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 585695 T2336 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 585696 T2368 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 585696 T2382 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 585697 T2341 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 585697 T2396 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 585704 T2310 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 585705 T2310 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 585713 T2350 oasha.CollectionsHandler.handleCreateAction Creating Collection : numShards=2&collection.configName=conf1&name=solrj_collection&replicationFactor=2&action=CREATE&router.field=myOwnField&wt=javabin&router.name=compositeId&version=2
[junit4] 2> 585714 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 585716 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/collection-queue-work state: SyncConnected type NodeChildrenChanged
[junit4] 2> 585717 T2338 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":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField",
[junit4] 2> "router.name":"compositeId"}
[junit4] 2> 585717 T2338 oasc.OverseerCollectionProcessor.processMessage WARN OverseerCollectionProcessor.processMessage : createcollection , {
[junit4] 2> "operation":"createcollection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "name":"solrj_collection",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"2",
[junit4] 2> "router.field":"myOwnField",
[junit4] 2> "router.name":"compositeId"}
[junit4] 2> 585718 T2338 oasc.OverseerCollectionProcessor.createConfNode creating collections conf node /collections/solrj_collection
[junit4] 2> 585718 T2338 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection
[junit4] 2> 585723 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 585726 T2337 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 585726 T2337 oasc.Overseer$ClusterStateUpdater.buildCollection building a new collection: solrj_collection
[junit4] 2> 585727 T2337 oasc.Overseer$ClusterStateUpdater.createCollection Create collection solrj_collection with shards [shard1, shard2]
[junit4] 2> 585730 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 585731 T2354 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 585731 T2396 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 585732 T2341 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 585732 T2368 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 585732 T2336 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 585732 T2382 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 585824 T2338 oasc.OverseerCollectionProcessor.createCollection going to create cores replicas shardNames [shard1, shard2] , repFactor : 2
[junit4] 2> 585824 T2338 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica1 as part of slice shard1 of collection solrj_collection on 127.0.0.1:59829_
[junit4] 2> 585825 T2338 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard1_replica2 as part of slice shard1 of collection solrj_collection on 127.0.0.1:44511_
[junit4] 2> 585826 T2338 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica1 as part of slice shard2 of collection solrj_collection on 127.0.0.1:41774_
[junit4] 2> 585827 T2338 oasc.OverseerCollectionProcessor.createCollection Creating shard solrj_collection_shard2_replica2 as part of slice shard2 of collection solrj_collection on 127.0.0.1:46069_
[junit4] 2> 585829 T2348 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica2 state=down
[junit4] 2> 585829 T2378 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica2 state=down
[junit4] 2> 585829 T2389 oasc.ZkController.publish publishing core=solrj_collection_shard2_replica1 state=down
[junit4] 2> 585829 T2364 oasc.ZkController.publish publishing core=solrj_collection_shard1_replica1 state=down
[junit4] 2> 585831 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 585832 T2348 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 585833 T2378 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 585834 T2389 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 585835 T2364 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 585839 T2337 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 585840 T2337 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:44511",
[junit4] 2> "core":"solrj_collection_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44511_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 585848 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 585852 T2337 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46069",
[junit4] 2> "core":"solrj_collection_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:46069_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 585860 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 585864 T2337 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41774",
[junit4] 2> "core":"solrj_collection_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:41774_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 585872 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 585876 T2337 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59829",
[junit4] 2> "core":"solrj_collection_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:59829_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"solrj_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 585883 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 585989 T2354 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 585990 T2382 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 585990 T2341 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 585990 T2336 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 585990 T2396 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 585992 T2368 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 586833 T2348 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica2
[junit4] 2> 586834 T2348 oasc.SolrXMLCoresLocator.doPersist Persisted core descriptions to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/solr.xml
[junit4] 2> 586834 T2378 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica2
[junit4] 2> 586834 T2348 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica2' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/solrj_collection_shard1_replica2
[junit4] 2> 586835 T2348 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 586835 T2389 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard2_replica1
[junit4] 2> 586836 T2364 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for solrj_collection_shard1_replica1
[junit4] 2> 586837 T2348 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 586837 T2348 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 586835 T2378 oasc.SolrXMLCoresLocator.doPersist Persisted core descriptions to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/solr.xml
[junit4] 2> 586837 T2364 oasc.SolrXMLCoresLocator.doPersist Persisted core descriptions to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/solr.xml
[junit4] 2> 586836 T2389 oasc.SolrXMLCoresLocator.doPersist Persisted core descriptions to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/solr.xml
[junit4] 2> 586838 T2364 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard1_replica1' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/solrj_collection_shard1_replica1
[junit4] 2> 586839 T2364 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 586838 T2378 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica2' using instanceDir: ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/solrj_collection_shard2_replica2
[junit4] 2> 586840 T2378 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 586839 T2348 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/solrj_collection_shard1_replica2/'
[junit4] 2> 586839 T2389 oasc.CoreContainer.create Creating SolrCore 'solrj_collection_shard2_replica1' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/solrj_collection_shard2_replica1
[junit4] 2> 586842 T2378 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 586842 T2389 oasc.ZkController.createCollectionZkNode Check for collection zkNode:solrj_collection
[junit4] 2> 586841 T2364 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 586843 T2364 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 586842 T2378 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 586844 T2389 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 586845 T2389 oascc.ZkStateReader.readConfigName Load collection config from:/collections/solrj_collection
[junit4] 2> 586846 T2378 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/solrj_collection_shard2_replica2/'
[junit4] 2> 586847 T2364 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/solrj_collection_shard1_replica1/'
[junit4] 2> 586849 T2389 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/solrj_collection_shard2_replica1/'
[junit4] 2> 586923 T2348 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 586929 T2364 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 586932 T2389 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 586933 T2378 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 586998 T2348 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 587001 T2348 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 587009 T2364 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 587011 T2348 oass.IndexSchema.readSchema [solrj_collection_shard1_replica2] Schema name=test
[junit4] 2> 587012 T2389 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 587012 T2364 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 587014 T2389 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 587015 T2378 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 587017 T2378 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 587044 T2364 oass.IndexSchema.readSchema [solrj_collection_shard1_replica1] Schema name=test
[junit4] 2> 587044 T2389 oass.IndexSchema.readSchema [solrj_collection_shard2_replica1] Schema name=test
[junit4] 2> 587044 T2378 oass.IndexSchema.readSchema [solrj_collection_shard2_replica2] Schema name=test
[junit4] 2> 587808 T2348 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 587809 T2348 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 587810 T2348 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 587814 T2364 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 587815 T2364 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 587817 T2364 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 587819 T2348 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 587823 T2348 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 587823 T2364 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 587828 T2364 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 587839 T2378 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 587840 T2378 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 587841 T2378 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 587848 T2389 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 587848 T2389 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 587849 T2389 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 587851 T2348 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 587852 T2378 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 587856 T2378 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 587857 T2364 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 587858 T2348 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 587858 T2389 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 587862 T2389 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 587863 T2364 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 587864 T2348 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 587869 T2348 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 587870 T2348 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 587870 T2348 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 587873 T2364 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 587878 T2364 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 587879 T2348 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 587879 T2348 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 587879 T2364 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 587880 T2348 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 587880 T2364 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 587881 T2348 oasc.SolrCore.<init> [solrj_collection_shard1_replica2] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/solrj_collection_shard1_replica2/, dataDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/solrj_collection_shard1_replica2/data/
[junit4] 2> 587882 T2348 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fcb5c1
[junit4] 2> 587883 T2348 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/solrj_collection_shard1_replica2/data
[junit4] 2> 587883 T2348 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/solrj_collection_shard1_replica2/data/index/
[junit4] 2> 587884 T2348 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica2] Solr index directory '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/solrj_collection_shard1_replica2/data/index' doesn't exist. Creating new index...
[junit4] 2> 587885 T2348 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty1-1389769793104/solrj_collection_shard1_replica2/data/index
[junit4] 2> 587885 T2348 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=39, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6284142259252714]
[junit4] 2> 587885 T2364 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 587886 T2364 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 587886 T2348 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d6b06f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@120ec31),segFN=segments_1,generation=1}
[junit4] 2> 587887 T2364 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 587887 T2348 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 587888 T2364 oasc.SolrCore.<init> [solrj_collection_shard1_replica1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/solrj_collection_shard1_replica1/, dataDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/solrj_collection_shard1_replica1/data/
[junit4] 2> 587889 T2364 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fcb5c1
[junit4] 2> 587890 T2364 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/solrj_collection_shard1_replica1/data
[junit4] 2> 587890 T2364 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/solrj_collection_shard1_replica1/data/index/
[junit4] 2> 587891 T2364 oasc.SolrCore.initIndex WARN [solrj_collection_shard1_replica1] Solr index directory '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/solrj_collection_shard1_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 587891 T2348 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 587891 T2364 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty2-1389769794315/solrj_collection_shard1_replica1/data/index
[junit4] 2> 587892 T2348 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 587892 T2364 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=39, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6284142259252714]
[junit4] 2> 587893 T2348 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 587893 T2348 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 587893 T2364 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@11db01b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@16ec5d6),segFN=segments_1,generation=1}
[junit4] 2> 587894 T2348 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 587894 T2378 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 587894 T2364 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 587895 T2348 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 587896 T2348 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 587897 T2348 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 587897 T2348 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 587898 T2348 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 587898 T2348 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 587899 T2348 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 587899 T2348 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 587899 T2364 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 587900 T2348 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 587900 T2364 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 587900 T2348 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 587901 T2378 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 587901 T2348 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 587900 T2364 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 587902 T2364 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 587902 T2364 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 587903 T2364 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 587903 T2364 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 587903 T2348 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 587904 T2389 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 587904 T2364 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 587905 T2364 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 587906 T2364 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 587907 T2364 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 587907 T2364 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 587907 T2378 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 587907 T2348 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 587908 T2348 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 587907 T2364 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 587909 T2348 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 587910 T2389 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 587910 T2348 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d6b06f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@120ec31),segFN=segments_1,generation=1}
[junit4] 2> 587910 T2364 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 587910 T2378 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 587911 T2364 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 587910 T2348 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 587912 T2364 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 587911 T2378 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 587912 T2348 oass.SolrIndexSearcher.<init> Opening Searcher@1a518cb main
[junit4] 2> 587913 T2378 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 587914 T2364 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 587914 T2389 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 587916 T2378 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 587916 T2378 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 587916 T2402 oasc.SolrCore.registerSearcher [solrj_collection_shard1_replica2] Registered new searcher Searcher@1a518cb main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 587917 T2389 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 587918 T2389 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 587917 T2378 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 587918 T2389 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 587918 T2364 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 587919 T2378 oasc.SolrCore.<init> [solrj_collection_shard2_replica2] Opening new SolrCore at ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/solrj_collection_shard2_replica2/, dataDir=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/solrj_collection_shard2_replica2/data/
[junit4] 2> 587920 T2348 oasc.CoreContainer.registerCore registering core: solrj_collection_shard1_replica2
[junit4] 2> 587920 T2364 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 587921 T2389 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 587921 T2364 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 587920 T2348 oasc.ZkController.register Register replica - core:solrj_collection_shard1_replica2 address:http://127.0.0.1:44511 collection:solrj_collection shard:shard1
[junit4] 2> 587920 T2378 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fcb5c1
[junit4] 2> 587922 T2364 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@11db01b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@16ec5d6),segFN=segments_1,generation=1}
[junit4] 2> 587921 T2389 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 587923 T2378 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/solrj_collection_shard2_replica2/data
[junit4] 2> 587923 T2378 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/solrj_collection_shard2_replica2/data/index/
[junit4] 2> 587923 T2364 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 587924 T2378 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica2] Solr index directory './org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/solrj_collection_shard2_replica2/data/index' doesn't exist. Creating new index...
[junit4] 2> 587923 T2389 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 587923 T2348 oascc.SolrZkClient.makePath makePath: /collections/solrj_collection/leader_elect/shard1/election
[junit4] 2> 587925 T2389 oasc.SolrCore.<init> [solrj_collection_shard2_replica1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/solrj_collection_shard2_replica1/, dataDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/solrj_collection_shard2_replica1/data/
[junit4] 2> 587925 T2378 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty3-1389769795534/solrj_collection_shard2_replica2/data/index
[junit4] 2> 587924 T2364 oass.SolrIndexSearcher.<init> Opening Searcher@a26476 main
[junit4] 2> 587926 T2378 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=39, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6284142259252714]
[junit4] 2> 587926 T2389 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1fcb5c1
[junit4] 2> 587927 T2378 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@171bfed lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dcb68c),segFN=segments_1,generation=1}
[junit4] 2> 587928 T2378 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 587928 T2389 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/solrj_collection_shard2_replica1/data
[junit4] 2> 587946 T2389 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/solrj_collection_shard2_replica1/data/index/
[junit4] 2> 587947 T2389 oasc.SolrCore.initIndex WARN [solrj_collection_shard2_replica1] Solr index directory '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/solrj_collection_shard2_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 587948 T2389 oasc.CachingDirectoryFactory.get return new directory for ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.CollectionsAPIDistributedZkTest-jetty4-1389769796755/solrj_collection_shard2_replica1/data/index
[junit4] 2> 587949 T2403 oasc.SolrCore.registerSearcher [solrj_collection_shard1_replica1] Registered new searcher Searcher@a26476 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 587949 T2389 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=39, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6284142259252714]
[junit4] 2> 587950 T2389 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1908f10 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f2cdec),segFN=segments_1,generation=1}
[junit4] 2> 587951 T2389 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 587951 T2364 oasc.CoreContainer.registerCore registering core: solrj_collection_shard1_replica1
[junit4] 2> 587951 T2378 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 587952 T2364 oasc.ZkController.register Register replica - core:solrj_collection_shard1_replica1 address:http://127.0.0.1:59829 collection:solrj_collection shard:shard1
[junit4] 2> 587952 T2378 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 587954 T2378 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 587954 T2378 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 587954 T2389 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 587954 T2378 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 587955 T2389 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 587955 T2378 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 587956 T2389 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 587956 T2378 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 587957 T2389 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 587957 T2348 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 587957 T2378 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 587958 T2378 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 587957 T2389 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 587959 T2378 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 587959 T2389 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 587960 T2378 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 587961 T2378 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 587960 T2389 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 587961 T2378 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 587961 T2389 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 587962 T2378 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 587962 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 587962 T2378 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 587962 T2389 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 587963 T2378 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 587964 T2389 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 587963 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 587963 T2348 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 587964 T2389 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 587965 T2389 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 587964 T2336 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 587965 T2389 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 587965 T2378 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 587966 T2389 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 587965 T2348 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 587967 T2337 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 587967 T2389 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> ASYNC NEW_CORE C2365 name=solrj_collection_shard1_replica2 org.apache.solr.core.SolrCore@1cae98e url=http://127.0.0.1:44511/solrj_collection_shard1_replica2 node=127.0.0.1:44511_ C2365_STATE=coll:solrj_collection core:solrj_collection_shard1_replica2 props:{state=down, base_url=http://127.0.0.1:44511, core=solrj_collection_shard1_replica2, node_nam
[...truncated too long message...]
ollection_2/leader_elect/shard4/election/91079953126981644-core_node9-n_0000000002
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:873)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:173)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:170)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:170)
[junit4] 2> at org.apache.solr.cloud.ElectionContext.cancelElection(ElectionContext.java:67)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:293)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:163)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:107)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:136)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> 797537 T2382 oasc.LeaderElector$1.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/awholynewcollection_1/leader_elect/shard4/election
[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.getChildren(ZooKeeper.java:1468)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:256)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:91)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:136)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> 797538 T2382 oasc.LeaderElector$1.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/awholynewcollection_1/leader_elect/shard3/election
[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.getChildren(ZooKeeper.java:1468)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:256)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:91)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:136)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> 797538 T2382 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 797538 T2382 oasc.LeaderElector$1.process WARN org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[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.getChildren(ZooKeeper.java:1468)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:256)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:253)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:91)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:136)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4] 2>
[junit4] 2> NOTE: test params are: codec=Lucene46: {timestamp=MockVariableIntBlock(baseBlockSize=43), range_facet_l=MockVariableIntBlock(baseBlockSize=43), id=PostingsFormat(name=SimpleText), range_facet_sl=PostingsFormat(name=SimpleText), a_t=MockVariableIntBlock(baseBlockSize=43), text=Pulsing41(freqCutoff=19 minBlockSize=50 maxBlockSize=102), range_facet_si=MockVariableIntBlock(baseBlockSize=43), _version_=PostingsFormat(name=Lucene41WithOrds), other_tl1=PostingsFormat(name=Lucene41WithOrds), multiDefault=MockVariableIntBlock(baseBlockSize=43), a_si=PostingsFormat(name=Lucene41WithOrds), intDefault=PostingsFormat(name=Lucene41WithOrds)}, docValues:{}, sim=DefaultSimilarity, locale=zh_SG, timezone=America/Scoresbysund
[junit4] 2> NOTE: Linux 3.8.0-35-generic i386/Sun Microsystems Inc. 1.6.0_45 (32-bit)/cpus=8,threads=1,free=88992056,total=224673792
[junit4] 2> NOTE: All tests run in this JVM: [TestFastLRUCache, HdfsChaosMonkeySafeLeaderTest, TestIndexSearcher, SpellCheckComponentTest, TestFastWriter, SpellingQueryConverterTest, TestRangeQuery, SolrPluginUtilsTest, TestNRTOpen, TestSolrDeletionPolicy2, DistanceFunctionTest, HighlighterConfigTest, BlockDirectoryTest, TestNoOpRegenerator, LeaderElectionIntegrationTest, DistributedQueryComponentCustomSortTest, ChaosMonkeySafeLeaderTest, TestCursorMarkWithoutUniqueKey, TestRTGBase, PluginInfoTest, OpenExchangeRatesOrgProviderTest, TestStressLucene, UniqFieldsUpdateProcessorFactoryTest, TestOmitPositions, FullSolrCloudDistribCmdsTest, TestQuerySenderNoQuery, ZkNodePropsTest, TestIndexingPerformance, RequiredFieldsTest, TestZkChroot, MigrateRouteKeyTest, CurrencyFieldXmlFileTest, TestRecovery, TestSolr4Spatial, NoCacheHeaderTest, TestUniqueKeyFieldResource, SolrRequestParserTest, CachingDirectoryFactoryTest, TestDynamicFieldCollectionResource, SolrCoreCheckLockOnStartupTest, StressHdfsTest, TestBinaryField, SolrTestCaseJ4Test, SortByFunctionTest, TestManagedSchemaFieldResource, TestDefaultSimilarityFactory, TestStressRecovery, TestDistributedGrouping, TestReload, TestDefaultSearchFieldResource, DocValuesMissingTest, CoreMergeIndexesAdminHandlerTest, SuggesterTSTTest, TimeZoneUtilsTest, TestHashPartitioner, DocumentAnalysisRequestHandlerTest, TestCollationKeyRangeQueries, IndexBasedSpellCheckerTest, TermVectorComponentDistributedTest, StatsComponentTest, NotRequiredUniqueKeyTest, TestCodecSupport, TestLazyCores, SpellCheckCollatorTest, TestFiltering, TestSolrCoreProperties, ConvertedLegacyTest, XsltUpdateRequestHandlerTest, ConnectionManagerTest, OutputWriterTest, RequestHandlersTest, ShardRoutingCustomTest, JSONWriterTest, HighlighterTest, SuggesterWFSTTest, TestJmxIntegration, TestSolrJ, TestBinaryResponseWriter, TestIBSimilarityFactory, PingRequestHandlerTest, TestMultiCoreConfBootstrap, DocValuesMultiTest, CollectionsAPIDistributedZkTest]
[junit4] Completed on J1 in 217.41s, 1 test, 1 failure <<< FAILURES!
[...truncated 540 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:459: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:439: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:491: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1307: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:940: There were test failures: 352 suites, 1538 tests, 1 failure, 34 ignored (6 assumptions)
Total time: 54 minutes 8 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.6.0_45 -client -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure