lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.8.0-ea-b65) - Build # 4490 - Failure!
Date Thu, 28 Feb 2013 13:08:59 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/4490/
Java: 64bit/jdk1.8.0-ea-b65 -XX:+UseSerialGC

2 tests failed.
REGRESSION:  org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch

Error Message:
expected:<1> but was:<0>

Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
	at __randomizedtesting.SeedInfo.seed([CECB4637E543672E:4F2DC82F921C0712]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:205)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:85)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:805)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:474)
	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:722)


REGRESSION:  org.apache.solr.cloud.UnloadDistributedZkTest.testDistribSearch

Error Message:
Still found shard

Stack Trace:
java.lang.AssertionError: Still found shard
	at __randomizedtesting.SeedInfo.seed([CECB4637E543672E:4F2DC82F921C0712]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.UnloadDistributedZkTest.testUnloadShardAndCollection(UnloadDistributedZkTest.java:120)
	at org.apache.solr.cloud.UnloadDistributedZkTest.doTest(UnloadDistributedZkTest.java:73)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:805)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:474)
	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:722)




Build Log:
[...truncated 9179 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4:junit4]   2> 0 T1591 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4]   2> 3 T1591 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362056406430
[junit4:junit4]   2> 4 T1591 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T1592 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 5 T1592 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 5 T1592 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 5 T1592 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 5 T1592 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 6 T1592 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 104 T1591 oasc.ZkTestServer.run start zk server on port:51006
[junit4:junit4]   2> 105 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@6c034090
[junit4:junit4]   2> 106 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T1597 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 107 T1597 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 107 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60229
[junit4:junit4]   2> 108 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60229
[junit4:junit4]   2> 108 T1595 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 370 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a00000 with negotiated timeout 10000 for client /127.0.0.1:60229
[junit4:junit4]   2> 370 T1597 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a00000, negotiated timeout = 10000
[junit4:junit4]   2> 371 T1598 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6c034090 name:ZooKeeperConnection Watcher:127.0.0.1:51006 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 371 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 372 T1591 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 376 T1596 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d20e365a00000
[junit4:junit4]   2> 377 T1598 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 378 T1593 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60229 which had sessionid 0x13d20e365a00000
[junit4:junit4]   2> 377 T1591 oaz.ZooKeeper.close Session: 0x13d20e365a00000 closed
[junit4:junit4]   2> 379 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@5673aca9
[junit4:junit4]   2> 380 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 380 T1599 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 382 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60230
[junit4:junit4]   2> 382 T1599 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 382 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60230
[junit4:junit4]   2> 386 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a00001 with negotiated timeout 10000 for client /127.0.0.1:60230
[junit4:junit4]   2> 386 T1599 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a00001, negotiated timeout = 10000
[junit4:junit4]   2> 386 T1600 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5673aca9 name:ZooKeeperConnection Watcher:127.0.0.1:51006/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 386 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 386 T1591 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 388 T1591 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 389 T1591 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 390 T1591 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 391 T1591 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:junit4]   2> 394 T1591 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 396 T1591 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:junit4]   2> 397 T1591 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 400 T1591 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:junit4]   2> 400 T1591 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 403 T1591 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:junit4]   2> 403 T1591 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 405 T1591 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:junit4]   2> 406 T1591 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 407 T1591 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:junit4]   2> 407 T1591 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 409 T1591 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:junit4]   2> 409 T1591 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 410 T1591 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:junit4]   2> 410 T1591 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 412 T1591 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:junit4]   2> 412 T1591 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 413 T1596 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d20e365a00001
[junit4:junit4]   2> 414 T1600 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 414 T1593 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60230 which had sessionid 0x13d20e365a00001
[junit4:junit4]   2> 414 T1591 oaz.ZooKeeper.close Session: 0x13d20e365a00001 closed
[junit4:junit4]   2> 465 T1591 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 479 T1591 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:48924
[junit4:junit4]   2> 479 T1591 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 480 T1591 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 480 T1591 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.BasicDistributedZk2Test-controljetty-1362056406840
[junit4:junit4]   2> 481 T1591 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362056406840/solr.xml
[junit4:junit4]   2> 481 T1591 oasc.CoreContainer.<init> New CoreContainer 1057015046
[junit4:junit4]   2> 482 T1591 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362056406840/'
[junit4:junit4]   2> 482 T1591 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.BasicDistributedZk2Test-controljetty-1362056406840/'
[junit4:junit4]   2> 496 T1591 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 497 T1591 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 497 T1591 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 497 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 497 T1591 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 498 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 498 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 498 T1591 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 498 T1591 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 499 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 504 T1591 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 512 T1591 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51006/solr
[junit4:junit4]   2> 512 T1591 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 512 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@5377c4f0
[junit4:junit4]   2> 513 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 513 T1610 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 514 T1610 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 514 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60231
[junit4:junit4]   2> 514 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60231
[junit4:junit4]   2> 515 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a00002 with negotiated timeout 20000 for client /127.0.0.1:60231
[junit4:junit4]   2> 515 T1610 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a00002, negotiated timeout = 20000
[junit4:junit4]   2> 516 T1611 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5377c4f0 name:ZooKeeperConnection Watcher:127.0.0.1:51006 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 516 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 517 T1596 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d20e365a00002
[junit4:junit4]   2> 517 T1611 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 517 T1593 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60231 which had sessionid 0x13d20e365a00002
[junit4:junit4]   2> 517 T1591 oaz.ZooKeeper.close Session: 0x13d20e365a00002 closed
[junit4:junit4]   2> 518 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 520 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@3887689a
[junit4:junit4]   2> 520 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 521 T1612 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 521 T1612 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 521 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60232
[junit4:junit4]   2> 522 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60232
[junit4:junit4]   2> 522 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a00003 with negotiated timeout 20000 for client /127.0.0.1:60232
[junit4:junit4]   2> 522 T1612 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a00003, negotiated timeout = 20000
[junit4:junit4]   2> 522 T1613 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3887689a name:ZooKeeperConnection Watcher:127.0.0.1:51006/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 523 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 524 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 525 T1591 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 526 T1591 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48924_
[junit4:junit4]   2> 526 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:48924_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:48924_
[junit4:junit4]   2> 526 T1591 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48924_
[junit4:junit4]   2> 528 T1591 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 530 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:delete cxid:0x16 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 531 T1591 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 532 T1591 oasc.Overseer.start Overseer (id=89263728651927555-127.0.0.1:48924_-n_0000000000) starting
[junit4:junit4]   2> 532 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 533 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 533 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 534 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 534 T1615 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 534 T1591 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 535 T1591 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 536 T1614 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 538 T1616 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362056406840/collection1
[junit4:junit4]   2> 538 T1616 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 539 T1616 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 539 T1616 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 539 T1616 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.BasicDistributedZk2Test-controljetty-1362056406840/collection1/'
[junit4:junit4]   2> 540 T1616 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362056406840/collection1/lib/README' to classloader
[junit4:junit4]   2> 540 T1616 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362056406840/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 570 T1616 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 610 T1616 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 611 T1616 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 615 T1616 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 940 T1616 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 945 T1616 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 947 T1616 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 960 T1616 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 963 T1616 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 965 T1616 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 966 T1616 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 967 T1616 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362056406840/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data/
[junit4:junit4]   2> 967 T1616 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cf590bb
[junit4:junit4]   2> 967 T1616 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 973 T1616 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.BasicDistributedZk2Test-1362056406429/control/data forceNew:false
[junit4:junit4]   2> 974 T1616 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data
[junit4:junit4]   2> 974 T1616 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data/index/
[junit4:junit4]   2> 974 T1616 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 974 T1616 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.BasicDistributedZk2Test-1362056406429/control/data/index forceNew:false
[junit4:junit4]   2> 978 T1616 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6df7035a; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 979 T1616 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 979 T1616 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data/index
[junit4:junit4]   2> 980 T1616 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 980 T1616 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 981 T1616 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 981 T1616 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 981 T1616 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 981 T1616 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 981 T1616 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 982 T1616 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 982 T1616 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 984 T1616 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 986 T1616 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data
[junit4:junit4]   2> 986 T1616 oass.SolrIndexSearcher.<init> Opening Searcher@39b430e0 main
[junit4:junit4]   2> 987 T1616 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 987 T1616 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 989 T1617 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@39b430e0 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 990 T1616 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 990 T1616 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 992 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:create cxid:0x50 zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 2039 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2040 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48924_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48924",
[junit4:junit4]   2> 	  "state":"down"}
[junit4:junit4]   2> 2041 T1614 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 2041 T1614 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 2042 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:create cxid:0x58 zxid:0x2c txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 2048 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 2993 T1616 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2994 T1616 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:48924 collection:control_collection shard:shard1
[junit4:junit4]   2> 2995 T1616 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 3000 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:delete cxid:0x72 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 3001 T1616 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3001 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3002 T1616 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3003 T1616 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3003 T1616 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:48924/collection1/
[junit4:junit4]   2> 3003 T1616 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3003 T1616 oasc.SyncStrategy.syncToMe http://127.0.0.1:48924/collection1/ has no replicas
[junit4:junit4]   2> 3004 T1616 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:48924/collection1/
[junit4:junit4]   2> 3004 T1616 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 3007 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3550 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3554 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 3559 T1616 oasc.ZkController.register We are http://127.0.0.1:48924/collection1/ and leader is http://127.0.0.1:48924/collection1/
[junit4:junit4]   2> 3560 T1616 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48924
[junit4:junit4]   2> 3560 T1616 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3560 T1616 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 3560 T1616 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3561 T1616 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3562 T1591 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3562 T1591 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3562 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3568 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 3570 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@6b4da841
[junit4:junit4]   2> 3571 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3571 T1618 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3571 T1618 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 3572 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60249
[junit4:junit4]   2> 3572 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60249
[junit4:junit4]   2> 3573 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a00004 with negotiated timeout 10000 for client /127.0.0.1:60249
[junit4:junit4]   2> 3577 T1618 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a00004, negotiated timeout = 10000
[junit4:junit4]   2> 3581 T1619 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6b4da841 name:ZooKeeperConnection Watcher:127.0.0.1:51006/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3581 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3582 T1591 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3583 T1591 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 3650 T1591 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 3652 T1591 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:32813
[junit4:junit4]   2> 3653 T1591 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3653 T1591 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3653 T1591 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.BasicDistributedZk2Test-jetty1-1362056410009
[junit4:junit4]   2> 3654 T1591 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362056410009/solr.xml
[junit4:junit4]   2> 3654 T1591 oasc.CoreContainer.<init> New CoreContainer 1243697827
[junit4:junit4]   2> 3654 T1591 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362056410009/'
[junit4:junit4]   2> 3655 T1591 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.BasicDistributedZk2Test-jetty1-1362056410009/'
[junit4:junit4]   2> 3674 T1591 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 3674 T1591 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3675 T1591 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 3675 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3675 T1591 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3676 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3676 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3677 T1591 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3677 T1591 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3677 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 3686 T1591 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3695 T1591 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51006/solr
[junit4:junit4]   2> 3695 T1591 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 3696 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@57406da3
[junit4:junit4]   2> 3696 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3697 T1629 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3697 T1629 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 3698 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60250
[junit4:junit4]   2> 3701 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60250
[junit4:junit4]   2> 3702 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a00005 with negotiated timeout 20000 for client /127.0.0.1:60250
[junit4:junit4]   2> 3702 T1629 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a00005, negotiated timeout = 20000
[junit4:junit4]   2> 3702 T1630 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@57406da3 name:ZooKeeperConnection Watcher:127.0.0.1:51006 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3703 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3706 T1596 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d20e365a00005
[junit4:junit4]   2> 3706 T1593 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60250 which had sessionid 0x13d20e365a00005
[junit4:junit4]   2> 3708 T1630 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 3708 T1591 oaz.ZooKeeper.close Session: 0x13d20e365a00005 closed
[junit4:junit4]   2> 3709 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 3713 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@605c8331
[junit4:junit4]   2> 3715 T1631 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3715 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60251
[junit4:junit4]   2> 3716 T1631 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 3716 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60251
[junit4:junit4]   2> 3717 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a00006 with negotiated timeout 20000 for client /127.0.0.1:60251
[junit4:junit4]   2> 3717 T1631 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a00006, negotiated timeout = 20000
[junit4:junit4]   2> 3720 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3720 T1632 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@605c8331 name:ZooKeeperConnection Watcher:127.0.0.1:51006/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3721 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3721 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3722 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3722 T1591 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4724 T1591 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32813_
[junit4:junit4]   2> 4725 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00006 type:delete cxid:0x9 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:32813_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:32813_
[junit4:junit4]   2> 4725 T1591 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:32813_
[junit4:junit4]   2> 4726 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 4727 T1619 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4727 T1632 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4727 T1613 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4741 T1633 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362056410009/collection1
[junit4:junit4]   2> 4741 T1633 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4742 T1633 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4742 T1633 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4743 T1633 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.BasicDistributedZk2Test-jetty1-1362056410009/collection1/'
[junit4:junit4]   2> 4743 T1633 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362056410009/collection1/lib/README' to classloader
[junit4:junit4]   2> 4743 T1633 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362056410009/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4763 T1633 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 4787 T1633 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4788 T1633 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4791 T1633 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5057 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5057 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48924_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48924",
[junit4:junit4]   2> 	  "state":"active"}
[junit4:junit4]   2> 5059 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 5059 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 5059 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 5087 T1633 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5091 T1633 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5093 T1633 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5101 T1633 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5104 T1633 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5106 T1633 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5107 T1633 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5108 T1633 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362056410009/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1/
[junit4:junit4]   2> 5108 T1633 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cf590bb
[junit4:junit4]   2> 5108 T1633 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 5109 T1633 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.BasicDistributedZk2Test-1362056406429/jetty1 forceNew:false
[junit4:junit4]   2> 5109 T1633 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1
[junit4:junit4]   2> 5109 T1633 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1/index/
[junit4:junit4]   2> 5110 T1633 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5110 T1633 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.BasicDistributedZk2Test-1362056406429/jetty1/index forceNew:false
[junit4:junit4]   2> 5113 T1633 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@dfbdd04; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5114 T1633 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5114 T1633 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1/index
[junit4:junit4]   2> 5115 T1633 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5116 T1633 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5116 T1633 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5116 T1633 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5117 T1633 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5117 T1633 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5117 T1633 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5117 T1633 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5118 T1633 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5120 T1633 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5122 T1633 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1
[junit4:junit4]   2> 5123 T1633 oass.SolrIndexSearcher.<init> Opening Searcher@1c974cf8 main
[junit4:junit4]   2> 5124 T1633 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5124 T1633 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5135 T1634 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c974cf8 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5136 T1633 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 5136 T1633 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6563 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6564 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:32813_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:32813",
[junit4:junit4]   2> 	  "state":"down"}
[junit4:junit4]   2> 6564 T1614 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 6564 T1614 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 6569 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 6569 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 6569 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 7137 T1633 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 7138 T1633 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:32813 collection:collection1 shard:shard2
[junit4:junit4]   2> 7139 T1633 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 7145 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00006 type:delete cxid:0x46 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 7145 T1633 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7146 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00006 type:create cxid:0x47 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7148 T1633 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7148 T1633 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7148 T1633 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:32813/collection1/
[junit4:junit4]   2> 7148 T1633 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 7149 T1633 oasc.SyncStrategy.syncToMe http://127.0.0.1:32813/collection1/ has no replicas
[junit4:junit4]   2> 7149 T1633 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:32813/collection1/
[junit4:junit4]   2> 7149 T1633 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 7153 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00006 type:create cxid:0x51 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8074 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8082 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 8082 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 8082 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 8108 T1633 oasc.ZkController.register We are http://127.0.0.1:32813/collection1/ and leader is http://127.0.0.1:32813/collection1/
[junit4:junit4]   2> 8108 T1633 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:32813
[junit4:junit4]   2> 8108 T1633 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 8108 T1633 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 8108 T1633 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8110 T1633 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8111 T1591 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 8112 T1591 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8112 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8172 T1591 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 8173 T1591 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:40127
[junit4:junit4]   2> 8174 T1591 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8174 T1591 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8175 T1591 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.BasicDistributedZk2Test-jetty2-1362056414544
[junit4:junit4]   2> 8175 T1591 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362056414544/solr.xml
[junit4:junit4]   2> 8175 T1591 oasc.CoreContainer.<init> New CoreContainer 1511325385
[junit4:junit4]   2> 8175 T1591 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362056414544/'
[junit4:junit4]   2> 8176 T1591 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.BasicDistributedZk2Test-jetty2-1362056414544/'
[junit4:junit4]   2> 8189 T1591 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 8189 T1591 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8189 T1591 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 8189 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8190 T1591 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8190 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8190 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8190 T1591 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8191 T1591 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8191 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 8196 T1591 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8203 T1591 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51006/solr
[junit4:junit4]   2> 8204 T1591 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 8204 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1ea37b79
[junit4:junit4]   2> 8205 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8205 T1644 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8206 T1644 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 8206 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60255
[junit4:junit4]   2> 8206 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60255
[junit4:junit4]   2> 8207 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a00007 with negotiated timeout 20000 for client /127.0.0.1:60255
[junit4:junit4]   2> 8207 T1644 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a00007, negotiated timeout = 20000
[junit4:junit4]   2> 8207 T1645 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ea37b79 name:ZooKeeperConnection Watcher:127.0.0.1:51006 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8207 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8208 T1596 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d20e365a00007
[junit4:junit4]   2> 8208 T1645 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 8208 T1591 oaz.ZooKeeper.close Session: 0x13d20e365a00007 closed
[junit4:junit4]   2> 8209 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 8209 T1593 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60255 which had sessionid 0x13d20e365a00007
[junit4:junit4]   2> 8211 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@4e80b1a4
[junit4:junit4]   2> 8212 T1646 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8211 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8212 T1646 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 8212 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60256
[junit4:junit4]   2> 8212 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60256
[junit4:junit4]   2> 8213 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a00008 with negotiated timeout 20000 for client /127.0.0.1:60256
[junit4:junit4]   2> 8213 T1646 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a00008, negotiated timeout = 20000
[junit4:junit4]   2> 8213 T1647 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4e80b1a4 name:ZooKeeperConnection Watcher:127.0.0.1:51006/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8213 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8214 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8214 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8251 T1591 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 9254 T1591 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40127_
[junit4:junit4]   2> 9255 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00008 type:delete cxid:0x9 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:40127_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:40127_
[junit4:junit4]   2> 9256 T1591 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:40127_
[junit4:junit4]   2> 9259 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 9259 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 9259 T1619 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9259 T1647 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9260 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9261 T1613 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9262 T1632 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9267 T1648 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362056414544/collection1
[junit4:junit4]   2> 9267 T1648 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 9268 T1648 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 9268 T1648 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 9269 T1648 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.BasicDistributedZk2Test-jetty2-1362056414544/collection1/'
[junit4:junit4]   2> 9270 T1648 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362056414544/collection1/lib/README' to classloader
[junit4:junit4]   2> 9270 T1648 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362056414544/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 9313 T1648 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 9351 T1648 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9352 T1648 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9354 T1648 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9591 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9592 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:32813_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:32813",
[junit4:junit4]   2> 	  "state":"active"}
[junit4:junit4]   2> 9594 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9594 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9594 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9594 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9697 T1648 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9701 T1648 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9703 T1648 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9715 T1648 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9717 T1648 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9719 T1648 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9720 T1648 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9721 T1648 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362056414544/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2/
[junit4:junit4]   2> 9721 T1648 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cf590bb
[junit4:junit4]   2> 9721 T1648 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 9722 T1648 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.BasicDistributedZk2Test-1362056406429/jetty2 forceNew:false
[junit4:junit4]   2> 9722 T1648 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2
[junit4:junit4]   2> 9722 T1648 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2/index/
[junit4:junit4]   2> 9722 T1648 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 9723 T1648 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.BasicDistributedZk2Test-1362056406429/jetty2/index forceNew:false
[junit4:junit4]   2> 9726 T1648 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@221041f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9726 T1648 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 9726 T1648 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2/index
[junit4:junit4]   2> 9727 T1648 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 9727 T1648 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 9728 T1648 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 9728 T1648 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9728 T1648 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9728 T1648 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 9729 T1648 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 9729 T1648 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9729 T1648 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9731 T1648 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9733 T1648 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2
[junit4:junit4]   2> 9733 T1648 oass.SolrIndexSearcher.<init> Opening Searcher@58d30002 main
[junit4:junit4]   2> 9734 T1648 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9734 T1648 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9739 T1649 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@58d30002 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9740 T1648 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 9740 T1648 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11097 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11098 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:40127_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:40127",
[junit4:junit4]   2> 	  "state":"down"}
[junit4:junit4]   2> 11098 T1614 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 11099 T1614 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 11103 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 11103 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 11103 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 11103 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 11742 T1648 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 11742 T1648 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:40127 collection:collection1 shard:shard1
[junit4:junit4]   2> 11743 T1648 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 11749 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00008 type:delete cxid:0x45 zxid:0x7e txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> 11750 T1648 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 11751 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00008 type:create cxid:0x46 zxid:0x7f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11753 T1648 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 11753 T1648 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 11753 T1648 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:40127/collection1/
[junit4:junit4]   2> 11754 T1648 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 11754 T1648 oasc.SyncStrategy.syncToMe http://127.0.0.1:40127/collection1/ has no replicas
[junit4:junit4]   2> 11754 T1648 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:40127/collection1/
[junit4:junit4]   2> 11755 T1648 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 11758 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00008 type:create cxid:0x4f zxid:0x82 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12607 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12610 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12611 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12610 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12610 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 12612 T1648 oasc.ZkController.register We are http://127.0.0.1:40127/collection1/ and leader is http://127.0.0.1:40127/collection1/
[junit4:junit4]   2> 12612 T1648 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:40127
[junit4:junit4]   2> 12612 T1648 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 12612 T1648 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 12612 T1648 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12613 T1648 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12613 T1591 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 12614 T1591 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 12614 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12666 T1591 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 12667 T1591 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60019
[junit4:junit4]   2> 12668 T1591 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 12669 T1591 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 12669 T1591 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.BasicDistributedZk2Test-jetty3-1362056419043
[junit4:junit4]   2> 12669 T1591 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362056419043/solr.xml
[junit4:junit4]   2> 12669 T1591 oasc.CoreContainer.<init> New CoreContainer 1183750535
[junit4:junit4]   2> 12670 T1591 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362056419043/'
[junit4:junit4]   2> 12670 T1591 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.BasicDistributedZk2Test-jetty3-1362056419043/'
[junit4:junit4]   2> 12684 T1591 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 12684 T1591 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 12684 T1591 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 12685 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 12685 T1591 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 12685 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 12685 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 12686 T1591 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 12686 T1591 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 12686 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 12692 T1591 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 12712 T1591 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51006/solr
[junit4:junit4]   2> 12713 T1591 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 12713 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@5aaf78e9
[junit4:junit4]   2> 12714 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12714 T1659 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12715 T1659 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 12715 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60268
[junit4:junit4]   2> 12715 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60268
[junit4:junit4]   2> 12716 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a00009 with negotiated timeout 20000 for client /127.0.0.1:60268
[junit4:junit4]   2> 12716 T1659 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a00009, negotiated timeout = 20000
[junit4:junit4]   2> 12716 T1660 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5aaf78e9 name:ZooKeeperConnection Watcher:127.0.0.1:51006 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12716 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12717 T1596 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d20e365a00009
[junit4:junit4]   2> 12719 T1660 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 12719 T1593 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60268 which had sessionid 0x13d20e365a00009
[junit4:junit4]   2> 12719 T1591 oaz.ZooKeeper.close Session: 0x13d20e365a00009 closed
[junit4:junit4]   2> 12720 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 12722 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@e904071
[junit4:junit4]   2> 12722 T1661 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12722 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12723 T1661 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 12723 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60269
[junit4:junit4]   2> 12723 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60269
[junit4:junit4]   2> 12724 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a0000a with negotiated timeout 20000 for client /127.0.0.1:60269
[junit4:junit4]   2> 12724 T1661 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a0000a, negotiated timeout = 20000
[junit4:junit4]   2> 12725 T1662 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e904071 name:ZooKeeperConnection Watcher:127.0.0.1:51006/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12725 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12725 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a0000a type:create cxid:0x1 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12726 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a0000a type:create cxid:0x2 zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12726 T1591 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 13728 T1591 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60019_
[junit4:junit4]   2> 13728 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a0000a type:delete cxid:0x9 zxid:0x91 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60019_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60019_
[junit4:junit4]   2> 13729 T1591 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60019_
[junit4:junit4]   2> 13730 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 13730 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 13730 T1619 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13730 T1662 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13730 T1632 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13730 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 13730 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 13731 T1613 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13731 T1647 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13734 T1663 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362056419043/collection1
[junit4:junit4]   2> 13734 T1663 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 13735 T1663 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 13735 T1663 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 13736 T1663 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.BasicDistributedZk2Test-jetty3-1362056419043/collection1/'
[junit4:junit4]   2> 13736 T1663 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362056419043/collection1/lib/README' to classloader
[junit4:junit4]   2> 13736 T1663 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362056419043/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 13758 T1663 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 13800 T1663 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 13800 T1663 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 13804 T1663 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 14113 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14114 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:40127_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:40127",
[junit4:junit4]   2> 	  "state":"active"}
[junit4:junit4]   2> 14118 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14118 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14118 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14118 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14118 T1662 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14255 T1663 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 14271 T1663 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 14274 T1663 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 14282 T1663 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 14285 T1663 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 14288 T1663 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 14289 T1663 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 14290 T1663 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362056419043/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/
[junit4:junit4]   2> 14290 T1663 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cf590bb
[junit4:junit4]   2> 14291 T1663 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 14291 T1663 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.BasicDistributedZk2Test-1362056406429/jetty3 forceNew:false
[junit4:junit4]   2> 14292 T1663 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3
[junit4:junit4]   2> 14292 T1663 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index/
[junit4:junit4]   2> 14292 T1663 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 14293 T1663 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.BasicDistributedZk2Test-1362056406429/jetty3/index forceNew:false
[junit4:junit4]   2> 14321 T1663 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@41ecd1f6; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14321 T1663 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 14322 T1663 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index
[junit4:junit4]   2> 14323 T1663 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 14323 T1663 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 14323 T1663 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 14324 T1663 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 14324 T1663 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 14324 T1663 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 14324 T1663 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 14324 T1663 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 14325 T1663 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 14327 T1663 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 14329 T1663 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3
[junit4:junit4]   2> 14329 T1663 oass.SolrIndexSearcher.<init> Opening Searcher@38be44cc main
[junit4:junit4]   2> 14330 T1663 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 14330 T1663 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 14334 T1664 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@38be44cc main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 14336 T1663 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 14336 T1663 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 15620 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15621 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:60019_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60019",
[junit4:junit4]   2> 	  "state":"down"}
[junit4:junit4]   2> 15621 T1614 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 15621 T1614 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 15624 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15624 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15624 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15624 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15624 T1662 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 16337 T1663 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 16337 T1663 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60019 collection:collection1 shard:shard2
[junit4:junit4]   2> 16339 T1663 oasc.ZkController.register We are http://127.0.0.1:60019/collection1/ and leader is http://127.0.0.1:32813/collection1/
[junit4:junit4]   2> 16339 T1663 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60019
[junit4:junit4]   2> 16339 T1663 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 16339 T1663 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C70 name=collection1 org.apache.solr.core.SolrCore@1e84f5ae url=http://127.0.0.1:60019/collection1 node=127.0.0.1:60019_ C70_STATE=coll:collection1 core:collection1 props:{core=collection1, node_name=127.0.0.1:60019_, collection=collection1, base_url=http://127.0.0.1:60019, state=down}
[junit4:junit4]   2> 16344 T1665 C70 P60019 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 16344 T1663 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16344 T1665 C70 P60019 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 16345 T1665 C70 P60019 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 16345 T1665 C70 P60019 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16345 T1591 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 16345 T1591 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 16346 T1665 C70 P60019 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16346 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16414 T1591 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 16418 T1591 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55652
[junit4:junit4]   2> 16426 T1591 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 16427 T1591 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 16427 T1591 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.BasicDistributedZk2Test-jetty4-1362056422776
[junit4:junit4]   2> 16428 T1591 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1362056422776/solr.xml
[junit4:junit4]   2> 16428 T1591 oasc.CoreContainer.<init> New CoreContainer 913071669
[junit4:junit4]   2> 16429 T1591 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1362056422776/'
[junit4:junit4]   2> 16430 T1591 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.BasicDistributedZk2Test-jetty4-1362056422776/'
[junit4:junit4]   2> 16461 T1591 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 16462 T1591 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 16462 T1591 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 16463 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 16463 T1591 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 16463 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 16464 T1591 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 16464 T1591 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 16464 T1591 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 16465 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 16477 T1591 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 16494 T1591 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51006/solr
[junit4:junit4]   2> 16495 T1591 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 16495 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@5db4f788
[junit4:junit4]   2> 16496 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 16496 T1675 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 16497 T1675 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 16497 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60276
[junit4:junit4]   2> 16498 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60276
[junit4:junit4]   2> 16499 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a0000b with negotiated timeout 20000 for client /127.0.0.1:60276
[junit4:junit4]   2> 16499 T1675 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a0000b, negotiated timeout = 20000
[junit4:junit4]   2> 16499 T1676 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5db4f788 name:ZooKeeperConnection Watcher:127.0.0.1:51006 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16500 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 16501 T1596 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d20e365a0000b
[junit4:junit4]   2> 16502 T1593 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60276 which had sessionid 0x13d20e365a0000b
[junit4:junit4]   2> 16504 T1676 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 16504 T1591 oaz.ZooKeeper.close Session: 0x13d20e365a0000b closed
[junit4:junit4]   2> 16504 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 16508 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@2da65345
[junit4:junit4]   2> 16509 T1677 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 16509 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 16509 T1677 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 16509 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60277
[junit4:junit4]   2> 16510 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60277
[junit4:junit4]   2> 16510 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a0000c with negotiated timeout 20000 for client /127.0.0.1:60277
[junit4:junit4]   2> 16511 T1677 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:51006, sessionid = 0x13d20e365a0000c, negotiated timeout = 20000
[junit4:junit4]   2> 16511 T1678 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2da65345 name:ZooKeeperConnection Watcher:127.0.0.1:51006/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16511 T1591 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 16512 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a0000c type:create cxid:0x1 zxid:0xa2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16512 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a0000c type:create cxid:0x2 zxid:0xa3 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16514 T1591 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 17127 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 17128 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:60019_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60019",
[junit4:junit4]   2> 	  "state":"recovering"}
[junit4:junit4]   2> 17129 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17130 T1662 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17130 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17130 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17129 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17129 T1678 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17515 T1591 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55652_
[junit4:junit4]   2> 17516 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a0000c type:delete cxid:0xb zxid:0xa8 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55652_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55652_
[junit4:junit4]   2> 17517 T1591 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55652_
[junit4:junit4]   2> 17518 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17518 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17518 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17519 T1678 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17518 T1662 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17519 T1678 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 17518 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 17520 T1632 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17520 T1619 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17522 T1647 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17522 T1613 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17522 T1662 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17526 T1679 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1362056422776/collection1
[junit4:junit4]   2> 17527 T1679 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 17528 T1679 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 17528 T1679 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 17529 T1679 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.BasicDistributedZk2Test-jetty4-1362056422776/collection1/'
[junit4:junit4]   2> 17529 T1679 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1362056422776/collection1/lib/README' to classloader
[junit4:junit4]   2> 17529 T1679 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1362056422776/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 17563 T1679 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 17588 T1679 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 17589 T1679 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 17592 T1679 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 17955 T1679 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 17959 T1679 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 17961 T1679 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 17969 T1679 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17972 T1679 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17975 T1679 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17975 T1679 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17976 T1679 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1362056422776/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/
[junit4:junit4]   2> 17977 T1679 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cf590bb
[junit4:junit4]   2> 17977 T1679 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 17978 T1679 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.BasicDistributedZk2Test-1362056406429/jetty4 forceNew:false
[junit4:junit4]   2> 17978 T1679 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4
[junit4:junit4]   2> 17978 T1679 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index/
[junit4:junit4]   2> 17978 T1679 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 17979 T1679 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.BasicDistributedZk2Test-1362056406429/jetty4/index forceNew:false
[junit4:junit4]   2> 17982 T1679 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@4ca807e2; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 17982 T1679 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 17983 T1679 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index
[junit4:junit4]   2> 17985 T1679 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 17985 T1679 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 17985 T1679 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 17986 T1679 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 17986 T1679 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 17986 T1679 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 17986 T1679 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 17987 T1679 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 17987 T1679 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 17990 T1679 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 17995 T1679 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4
[junit4:junit4]   2> 17996 T1679 oass.SolrIndexSearcher.<init> Opening Searcher@4913bd38 main
[junit4:junit4]   2> 17996 T1679 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 17997 T1679 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 18000 T1680 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4913bd38 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 18001 T1679 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 18001 T1679 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 18633 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18633 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55652_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55652",
[junit4:junit4]   2> 	  "state":"down"}
[junit4:junit4]   2> 18633 T1614 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 18633 T1614 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 18636 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18636 T1662 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18637 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18637 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18636 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18636 T1678 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 19002 T1679 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 19002 T1679 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55652 collection:collection1 shard:shard1
[junit4:junit4]   2> 19004 T1679 oasc.ZkController.register We are http://127.0.0.1:55652/collection1/ and leader is http://127.0.0.1:40127/collection1/
[junit4:junit4]   2> 19004 T1679 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55652
[junit4:junit4]   2> 19004 T1679 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 19004 T1679 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C71 name=collection1 org.apache.solr.core.SolrCore@187f8628 url=http://127.0.0.1:55652/collection1 node=127.0.0.1:55652_ C71_STATE=coll:collection1 core:collection1 props:{core=collection1, node_name=127.0.0.1:55652_, collection=collection1, base_url=http://127.0.0.1:55652, state=down}
[junit4:junit4]   2> 19005 T1681 C71 P55652 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 19005 T1679 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19005 T1681 C71 P55652 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 19005 T1681 C71 P55652 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 19006 T1681 C71 P55652 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 19006 T1591 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 19006 T1591 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 19006 T1681 C71 P55652 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 19006 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 19010 T1591 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19012 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 19017 T1627 oasc.CoreContainer.create Creating SolrCore 'onenodecollectioncore' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362056410009/onenodecollectioncore
[junit4:junit4]   2> 19017 T1627 oasc.ZkController.createCollectionZkNode Check for collection zkNode:onenodecollection
[junit4:junit4]   2> 19018 T1627 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:onenodecollection
[junit4:junit4]   2> 19018 T1627 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4]   2> 19019 T1627 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4]   2> 19019 T1627 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection
[junit4:junit4]   2> 19021 T1627 oasc.ZkController.readConfigName Load collection config from:/collections/onenodecollection
[junit4:junit4]   2> 19022 T1627 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.BasicDistributedZk2Test-jetty1-1362056410009/onenodecollectioncore/'
[junit4:junit4]   2> 19070 T1627 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 19111 T1627 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 19111 T1627 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 19116 T1627 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2>  C70_STATE=coll:collection1 core:collection1 props:{core=collection1, node_name=127.0.0.1:60019_, collection=collection1, shard=shard2, base_url=http://127.0.0.1:60019, state=recovering}
[junit4:junit4]   2> 19357 T1665 C70 P60019 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:32813/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 19357 T1665 C70 P60019 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:60019 START replicas=[http://127.0.0.1:32813/collection1/] nUpdates=100
[junit4:junit4]   2> 19358 T1665 C70 P60019 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 19358 T1665 C70 P60019 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 19359 T1665 C70 P60019 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 19359 T1665 C70 P60019 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 19359 T1665 C70 P60019 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 19359 T1665 C70 P60019 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:32813/collection1/. core=collection1
[junit4:junit4]   2> 19359 T1665 C70 P60019 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C72 name=collection1 org.apache.solr.core.SolrCore@609b0163 url=http://127.0.0.1:32813/collection1 node=127.0.0.1:32813_ C72_STATE=coll:collection1 core:collection1 props:{core=collection1, node_name=127.0.0.1:32813_, collection=collection1, shard=shard2, base_url=http://127.0.0.1:32813, state=active, leader=true}
[junit4:junit4]   2> 19383 T1626 C72 P32813 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 19384 T1625 C72 P32813 REQ /get {version=2&distrib=false&getVersions=100&wt=javabin&qt=/get} status=0 QTime=1 
[junit4:junit4]   2> 19385 T1626 C72 P32813 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1
[junit4:junit4]   2> 19387 T1626 C72 P32813 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@dfbdd04; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 19388 T1626 C72 P32813 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 19399 T1626 C72 P32813 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@dfbdd04; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@dfbdd04; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19400 T1626 C72 P32813 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19401 T1626 C72 P32813 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1
[junit4:junit4]   2> 19402 T1626 C72 P32813 oass.SolrIndexSearcher.<init> Opening Searcher@30b4d605 realtime
[junit4:junit4]   2> 19402 T1626 C72 P32813 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 19403 T1626 C72 P32813 /update {waitSearcher=true&version=2&softCommit=false&openSearcher=false&commit=true&wt=javabin&commit_end_point=true} {commit=} 0 20
[junit4:junit4]   2> 19430 T1665 C70 P60019 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 19430 T1665 C70 P60019 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 19434 T1665 C70 P60019 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 19438 T1626 C72 P32813 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 19439 T1626 C72 P32813 REQ /replication {version=2&wt=javabin&qt=/replication&command=indexversion} status=0 QTime=3 
[junit4:junit4]   2> 19440 T1665 C70 P60019 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 19440 T1665 C70 P60019 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 19440 T1665 C70 P60019 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 19442 T1625 C72 P32813 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1
[junit4:junit4]   2> 19443 T1625 C72 P32813 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty1/index
[junit4:junit4]   2> 19443 T1625 C72 P32813 REQ /replication {generation=2&version=2&wt=javabin&qt=/replication&command=filelist} status=0 QTime=1 
[junit4:junit4]   2> 19444 T1665 C70 P60019 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 19448 T1665 C70 P60019 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.BasicDistributedZk2Test-1362056406429/jetty3/index.20130228060025871 forceNew:false
[junit4:junit4]   2> 19449 T1665 C70 P60019 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3
[junit4:junit4]   2> 19450 T1665 C70 P60019 oash.SnapPuller.fetchLatestIndex Starting download to NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index.20130228060025871 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3c1dca53; maxCacheMB=48.0 maxMergeSizeMB=4.0) fullCopy=true
[junit4:junit4]   2> 19456 T1628 C72 P32813 REQ /replication {generation=2&file=segments_2&wt=filestream&checksum=true&qt=/replication&command=filecontent} status=0 QTime=2 
[junit4:junit4]   2> 19463 T1665 C70 P60019 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index
[junit4:junit4]   2> 19463 T1665 C70 P60019 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 19476 T1665 C70 P60019 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130228060025871
[junit4:junit4]   2> 19477 T1665 C70 P60019 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3
[junit4:junit4]   2> 19478 T1665 C70 P60019 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3
[junit4:junit4]   2> 19478 T1665 C70 P60019 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index/ new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index.20130228060025871
[junit4:junit4]   2> 19479 T1665 C70 P60019 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index.20130228060025871 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3c1dca53; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19479 T1665 C70 P60019 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19480 T1665 C70 P60019 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3
[junit4:junit4]   2> 19481 T1665 C70 P60019 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3
[junit4:junit4]   2> 19482 T1665 C70 P60019 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 19482 T1665 C70 P60019 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 19482 T1665 C70 P60019 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 19489 T1665 C70 P60019 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index.20130228060025871
[junit4:junit4]   2> 19491 T1665 C70 P60019 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3
[junit4:junit4]   2> 19492 T1665 C70 P60019 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index.20130228060025871 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3c1dca53; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19492 T1665 C70 P60019 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19493 T1665 C70 P60019 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 19493 T1665 C70 P60019 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3
[junit4:junit4]   2> 19494 T1665 C70 P60019 oass.SolrIndexSearcher.<init> Opening Searcher@3b61f904 main
[junit4:junit4]   2> 19495 T1664 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3b61f904 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 19495 T1664 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index
[junit4:junit4]   2> 19495 T1665 C70 P60019 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index.20130228060025871
[junit4:junit4]   2> 19496 T1665 C70 P60019 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty3/index
[junit4:junit4]   2> 19496 T1665 C70 P60019 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 19496 T1665 C70 P60019 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 19496 T1665 C70 P60019 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 19496 T1665 C70 P60019 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 19498 T1665 C70 P60019 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 19564 T1627 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 19583 T1627 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 19586 T1627 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 19599 T1627 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 19605 T1627 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 19608 T1627 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 19609 T1627 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 19610 T1627 oasc.SolrCore.<init> [onenodecollectioncore] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362056410009/onenodecollectioncore/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362056406430/onenodecollection/
[junit4:junit4]   2> 19610 T1627 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cf590bb
[junit4:junit4]   2> 19611 T1627 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 19611 T1627 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362056406430/onenodecollection forceNew:false
[junit4:junit4]   2> 19612 T1627 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362056406430/onenodecollection
[junit4:junit4]   2> 19612 T1627 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/./solrtest-BasicDistributedZk2Test-1362056406430/onenodecollection/index/
[junit4:junit4]   2> 19613 T1627 oasc.SolrCore.initIndex WARNING [onenodecollectioncore] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362056406430/onenodecollection/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 19613 T1627 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362056406430/onenodecollection/index forceNew:false
[junit4:junit4]   2> 19617 T1627 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-BasicDistributedZk2Test-1362056406430/onenodecollection/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2f243755; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 19617 T1627 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 19618 T1627 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362056406430/onenodecollection/index
[junit4:junit4]   2> 19619 T1627 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 19620 T1627 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 19620 T1627 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 19621 T1627 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 19621 T1627 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 19622 T1627 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 19622 T1627 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 19622 T1627 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 19623 T1627 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 19626 T1627 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 19628 T1627 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362056406430/onenodecollection
[junit4:junit4]   2> 19629 T1627 oass.SolrIndexSearcher.<init> Opening Searcher@46cbc94e main
[junit4:junit4]   2> 19629 T1627 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 19630 T1627 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 19632 T1627 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362056406430/onenodecollection
[junit4:junit4]   2> 19633 T1684 oasc.SolrCore.registerSearcher [onenodecollectioncore] Registered new searcher Searcher@46cbc94e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 19634 T1627 oasc.ZkController.publish publishing core=onenodecollectioncore state=down
[junit4:junit4]   2> 20140 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 20141 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55652_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55652",
[junit4:junit4]   2> 	  "state":"recovering"}
[junit4:junit4]   2> 20144 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:60019_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60019",
[junit4:junit4]   2> 	  "state":"active"}
[junit4:junit4]   2> 20148 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"onenodecollectioncore",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:32813_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"onenodecollection",
[junit4:junit4]   2> 	  "roles":"none",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:32813",
[junit4:junit4]   2> 	  "state":"down"}
[junit4:junit4]   2> 20149 T1614 oasc.Overseer$ClusterStateUpdater.createCollection Create collection onenodecollection with numShards 1
[junit4:junit4]   2> 20149 T1614 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 20154 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20154 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20154 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20154 T1678 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20154 T1662 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20154 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 20636 T1627 oasc.CoreContainer.registerCore registering core: onenodecollectioncore
[junit4:junit4]   2> 20636 T1627 oasc.ZkController.register Register replica - core:onenodecollectioncore address:http://127.0.0.1:32813 collection:onenodecollection shard:shard1
[junit4:junit4]   2> 20637 T1627 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leader_elect/shard1/election
[junit4:junit4]   2> 20644 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00006 type:delete cxid:0xa6 zxid:0xc3 txntype:-1 reqpath:n/a Error Path:/solr/collections/onenodecollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/onenodecollection/leaders
[junit4:junit4]   2> 20645 T1627 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 20646 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00006 type:create cxid:0xa7 zxid:0xc4 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 20648 T1627 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 20649 T1627 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 20650 T1627 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:32813/onenodecollectioncore/
[junit4:junit4]   2> 20650 T1627 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 20651 T1627 oasc.SyncStrategy.syncToMe http://127.0.0.1:32813/onenodecollectioncore/ has no replicas
[junit4:junit4]   2> 20651 T1627 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:32813/onenodecollectioncore/
[junit4:junit4]   2> 20652 T1627 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leaders/shard1
[junit4:junit4]   2> 20657 T1596 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d20e365a00006 type:create cxid:0xb1 zxid:0xc8 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21661 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21667 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21667 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21667 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21667 T1662 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21667 T1678 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21667 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21711 T1627 oasc.ZkController.register We are http://127.0.0.1:32813/onenodecollectioncore/ and leader is http://127.0.0.1:32813/onenodecollectioncore/
[junit4:junit4]   2> 21712 T1627 oasc.ZkController.register No LogReplay needed for core=onenodecollectioncore baseURL=http://127.0.0.1:32813
[junit4:junit4]   2> 21712 T1627 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 21713 T1627 oasc.ZkController.publish publishing core=onenodecollectioncore state=active
[junit4:junit4]   2> 21714 T1627 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21715 T1591 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: onenodecollection failOnTimeout:true timeout (sec):230
[junit4:junit4]   2> 21716 T1591 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22717 T1591 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C71_STATE=coll:collection1 core:collection1 props:{core=collection1, node_name=127.0.0.1:55652_, collection=collection1, shard=shard1, base_url=http://127.0.0.1:55652, state=recovering}
[junit4:junit4]   2> 23011 T1681 C71 P55652 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:40127/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 23012 T1681 C71 P55652 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:55652 START replicas=[http://127.0.0.1:40127/collection1/] nUpdates=100
[junit4:junit4]   2> 23013 T1681 C71 P55652 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 23013 T1681 C71 P55652 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 23013 T1681 C71 P55652 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 23013 T1681 C71 P55652 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 23014 T1681 C71 P55652 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 23014 T1681 C71 P55652 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:40127/collection1/. core=collection1
[junit4:junit4]   2> 23014 T1681 C71 P55652 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C73 name=collection1 org.apache.solr.core.SolrCore@3f9a45e9 url=http://127.0.0.1:40127/collection1 node=127.0.0.1:40127_ C73_STATE=coll:collection1 core:collection1 props:{core=collection1, node_name=127.0.0.1:40127_, collection=collection1, shard=shard1, base_url=http://127.0.0.1:40127, state=active, leader=true}
[junit4:junit4]   2> 23020 T1641 C73 P40127 REQ /get {version=2&distrib=false&getVersions=100&wt=javabin&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 23022 T1642 C73 P40127 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 23024 T1642 C73 P40127 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2
[junit4:junit4]   2> 23026 T1642 C73 P40127 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@221041f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 23027 T1642 C73 P40127 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 23039 T1642 C73 P40127 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@221041f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@221041f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 23040 T1642 C73 P40127 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 23040 T1642 C73 P40127 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2
[junit4:junit4]   2> 23041 T1642 C73 P40127 oass.SolrIndexSearcher.<init> Opening Searcher@74ff1f43 realtime
[junit4:junit4]   2> 23042 T1642 C73 P40127 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23042 T1642 C73 P40127 /update {waitSearcher=true&version=2&softCommit=false&openSearcher=false&commit=true&wt=javabin&commit_end_point=true} {commit=} 0 20
[junit4:junit4]   2> 23046 T1681 C71 P55652 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 23046 T1681 C71 P55652 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 23049 T1640 C73 P40127 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 23050 T1640 C73 P40127 REQ /replication {version=2&wt=javabin&qt=/replication&command=indexversion} status=0 QTime=1 
[junit4:junit4]   2> 23050 T1681 C71 P55652 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 23050 T1681 C71 P55652 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 23050 T1681 C71 P55652 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 23052 T1641 C73 P40127 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2
[junit4:junit4]   2> 23052 T1641 C73 P40127 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty2/index
[junit4:junit4]   2> 23053 T1641 C73 P40127 REQ /replication {generation=2&version=2&wt=javabin&qt=/replication&command=filelist} status=0 QTime=1 
[junit4:junit4]   2> 23053 T1681 C71 P55652 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 23054 T1681 C71 P55652 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.BasicDistributedZk2Test-1362056406429/jetty4/index.20130228060029479 forceNew:false
[junit4:junit4]   2> 23054 T1681 C71 P55652 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4
[junit4:junit4]   2> 23054 T1681 C71 P55652 oash.SnapPuller.fetchLatestIndex Starting download to NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index.20130228060029479 lockFactory=org.apache.lucene.store.NativeFSLockFactory@942f179; maxCacheMB=48.0 maxMergeSizeMB=4.0) fullCopy=true
[junit4:junit4]   2> 23057 T1642 C73 P40127 REQ /replication {generation=2&file=segments_2&wt=filestream&checksum=true&qt=/replication&command=filecontent} status=0 QTime=1 
[junit4:junit4]   2> 23058 T1681 C71 P55652 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index
[junit4:junit4]   2> 23058 T1681 C71 P55652 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 23069 T1681 C71 P55652 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130228060029479
[junit4:junit4]   2> 23070 T1681 C71 P55652 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4
[junit4:junit4]   2> 23070 T1681 C71 P55652 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4
[junit4:junit4]   2> 23071 T1681 C71 P55652 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index/ new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index.20130228060029479
[junit4:junit4]   2> 23072 T1681 C71 P55652 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index.20130228060029479 lockFactory=org.apache.lucene.store.NativeFSLockFactory@942f179; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 23072 T1681 C71 P55652 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 23073 T1681 C71 P55652 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4
[junit4:junit4]   2> 23073 T1681 C71 P55652 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4
[junit4:junit4]   2> 23073 T1681 C71 P55652 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 23073 T1681 C71 P55652 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 23073 T1681 C71 P55652 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 23074 T1681 C71 P55652 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index.20130228060029479
[junit4:junit4]   2> 23074 T1681 C71 P55652 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4
[junit4:junit4]   2> 23075 T1681 C71 P55652 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index.20130228060029479 lockFactory=org.apache.lucene.store.NativeFSLockFactory@942f179; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 23075 T1681 C71 P55652 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 23076 T1681 C71 P55652 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 23076 T1681 C71 P55652 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4
[junit4:junit4]   2> 23076 T1681 C71 P55652 oass.SolrIndexSearcher.<init> Opening Searcher@203dbc1f main
[junit4:junit4]   2> 23077 T1680 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@203dbc1f main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 23077 T1680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index
[junit4:junit4]   2> 23078 T1681 C71 P55652 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index.20130228060029479
[junit4:junit4]   2> 23078 T1681 C71 P55652 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/jetty4/index
[junit4:junit4]   2> 23078 T1681 C71 P55652 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 23078 T1681 C71 P55652 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 23078 T1681 C71 P55652 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 23078 T1681 C71 P55652 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 23080 T1681 C71 P55652 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 23171 T1614 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 23171 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"onenodecollectioncore",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:32813_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"onenodecollection",
[junit4:junit4]   2> 	  "roles":"none",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:32813",
[junit4:junit4]   2> 	  "state":"active"}
[junit4:junit4]   2> 23174 T1614 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55652_",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55652",
[junit4:junit4]   2> 	  "state":"active"}
[junit4:junit4]   2> 23176 T1619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23176 T1632 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23177 T1662 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23177 T1613 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23176 T1647 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23176 T1678 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 23718 T1591 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 23720 T1591 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: onenodecollection
[junit4:junit4]   2> 23720 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C74 name=collection1 org.apache.solr.core.SolrCore@704ac3b1 url=http://127.0.0.1:48924/collection1 node=127.0.0.1:48924_ C74_STATE=coll:control_collection core:collection1 props:{core=collection1, node_name=127.0.0.1:48924_, collection=control_collection, shard=shard1, base_url=http://127.0.0.1:48924, state=active, leader=true}
[junit4:junit4]   2> 23756 T1609 C74 P48924 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data
[junit4:junit4]   2> 23760 T1609 C74 P48924 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6df7035a; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 23761 T1609 C74 P48924 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 23763 T1609 C74 P48924 /update {version=2&wt=javabin} {add=[1 (1428219683333472256)]} 0 8
[junit4:junit4]   2> 23780 T1606 C74 P48924 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 23809 T1606 C74 P48924 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6df7035a; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6df7035a; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0.fdt, _0.nvm, _0.fdx, _0.fnm, _0.tim, _0.si, _0.tip, _0.pos, _0.nvd, segments_2, _0.doc]
[junit4:junit4]   2> 23810 T1606 C74 P48924 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0.fdt, _0.nvm, _0.fdx, _0.fnm, _0.tim, _0.si, _0.tip, _0.pos, _0.nvd, segments_2, _0.doc]
[junit4:junit4]   2> 23811 T1606 C74 P48924 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data
[junit4:junit4]   2> 23818 T1606 C74 P48924 oass.SolrIndexSearcher.<init> Opening Searcher@7563a522 main
[junit4:junit4]   2> 23819 T1606 C74 P48924 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23820 T1617 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7563a522 main{StandardDirectoryReader(segments_2:3 _0(4.2):C1)}
[junit4:junit4]   2> 23821 T1617 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1362056406429/control/data/index
[junit4:junit4]   2> 23821 T1606 C74 P48924 /update {waitSearcher=true&version=2&softCommit=false&commit=true&wt=javabin} {commit=} 0 41
[junit4:junit4]   2> 23831 T1607 C74 P48924 REQ /select {version=2&q=*:*&wt=javabin} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 23837 T1591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C75 name=onenodecollectioncore org.apache.solr.core.SolrCore@68cf6dac url=http://127.0.0.1:32813/onenodecollectioncore node=127.0.0.1:32813_ C75_STATE=coll:onenodecollection core:onenodecollectioncore props:{core=onenodecollectioncore, node_name=127.0.0.1:32813_, collection=onenodecollection, roles=none, shard=shard1, base_url=http://127.0.0.1:32813, state=active, leader=true}
[junit4:junit4]   2> 23862 T1627 C75 P32813 REQ /select {version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 23864 T1591 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51006 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1f7d9945
[junit4:junit4]   2> 23866 T1688 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:51006. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 23867 T1593 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60290
[junit4:junit4]   2> 23868 T1591 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 23868 T1688 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51006, initiating session
[junit4:junit4]   2> 23868 T1593 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60290
[junit4:junit4]   2> 23870 T1595 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d20e365a0000d with negotiated timeout 10000 for client /127.0.0.1:60290
[junit4:junit4]   2> 23870 T1

[...truncated too long message...]

Dtests.file.encoding=ISO-8859-1
[junit4:junit4] FAILURE  152s J1 | UnloadDistributedZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: Still found shard
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([CECB4637E543672E:4F2DC82F921C0712]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.solr.cloud.UnloadDistributedZkTest.testUnloadShardAndCollection(UnloadDistributedZkTest.java:120)
[junit4:junit4]    > 	at org.apache.solr.cloud.UnloadDistributedZkTest.doTest(UnloadDistributedZkTest.java:73)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:805)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 151547 T3169 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 151555 T3168 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4:junit4]   2> 151650 T3172 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> NOTE: test params are: codec=Lucene3x, sim=DefaultSimilarity, locale=ar_TN, timezone=America/Santarem
[junit4:junit4]   2> NOTE: Linux 3.2.0-38-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=30375256,total=168624128
[junit4:junit4]   2> NOTE: All tests run in this JVM: [DocumentBuilderTest, TestDistributedSearch, TestPluginEnable, TestSort, TestMergePolicyConfig, UpdateParamsTest, ZkSolrClientTest, TestPropInject, StatsComponentTest, RequiredFieldsTest, URLClassifyProcessorTest, BasicZkTest, SuggesterTest, FileUtilsTest, ZkCLITest, TestCSVLoader, ZkNodePropsTest, DistributedQueryElevationComponentTest, ClusterStateUpdateTest, PeerSyncTest, TestArbitraryIndexDir, DocValuesMultiTest, TestMultiCoreConfBootstrap, ExternalFileFieldSortTest, TestRTGBase, SpellingQueryConverterTest, TestUtils, TestFuzzyAnalyzedSuggestions, PrimitiveFieldTypeTest, TestRecovery, TestZkChroot, ResponseLogComponentTest, TestDocSet, LegacyHTMLStripCharFilterTest, TestReversedWildcardFilterFactory, SuggesterTSTTest, TimeZoneUtilsTest, TestAtomicUpdateErrorCases, OverseerCollectionProcessorTest, DisMaxRequestHandlerTest, TestRealTimeGet, TestDistributedGrouping, DirectUpdateHandlerTest, TestCharFilters, QueryParsingTest, TestSurroundQueryParser, TestRandomDVFaceting, TestWriterPerf, TestFastOutputStream, DebugComponentTest, BasicFunctionalityTest, DOMUtilTest, TestDFRSimilarityFactory, BadIndexSchemaTest, StatelessScriptUpdateProcessorFactoryTest, OverseerTest, TestLRUCache, BasicDistributedZk2Test, TestLazyCores, SignatureUpdateProcessorFactoryTest, ReturnFieldsTest, TestHashPartitioner, UUIDFieldTest, HighlighterTest, RecoveryZkTest, TestReplicationHandler, LeaderElectionTest, ZkControllerTest, TestExtendedDismaxParser, SoftAutoCommitTest, TestFaceting, TestRangeQuery, TestCoreContainer, FileBasedSpellCheckerTest, ConvertedLegacyTest, TestBinaryResponseWriter, TestJmxIntegration, TestIndexSearcher, CoreAdminHandlerTest, TestFunctionQuery, TestTrie, TestRemoteStreaming, QueryEqualityTest, PolyFieldTest, SpatialFilterTest, TestCSVResponseWriter, MoreLikeThisHandlerTest, CurrencyFieldTest, IndexSchemaTest, BinaryUpdateRequestHandlerTest, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, TestQueryTypes, FieldAnalysisRequestHandlerTest, TermVectorComponentTest, DocumentAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest, TestSolrQueryParser, TestValueSourceCache, TestOmitPositions, LoggingHandlerTest, ShowFileRequestHandlerTest, TestSolrDeletionPolicy1, FieldMutatingUpdateProcessorTest, TestSolrDeletionPolicy2, DefaultValueUpdateProcessorTest, TestQuerySenderListener, IndexReaderFactoryTest, MultiTermTest, TestConfig, SpellPossibilityIteratorTest, TestPhraseSuggestions, TestIBSimilarityFactory, TestFastLRUCache, PrimUtilsTest, ResourceLoaderTest, TestSolrXMLSerializer, TestSuggestSpellingConverter, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest]
[junit4:junit4] Completed on J1 in 151.82s, 1 test, 1 failure <<< FAILURES!

[...truncated 34 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:381: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:361: 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/solr/build.xml:183: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1202: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:865: There were test failures: 258 suites, 1078 tests, 2 failures, 12 ignored (3 assumptions)

Total time: 35 minutes 33 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/jdk1.8.0-ea-b65 -XX:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message