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 (32bit/jdk1.8.0-ea-b65) - Build # 4500 - Failure!
Date Fri, 01 Mar 2013 04:20:04 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/4500/
Java: 32bit/jdk1.8.0-ea-b65 -server -XX:+UseSerialGC

1 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([83E66310DE19EA61:200ED08A9468A5D]: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:806)
	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 9629 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4:junit4]   2> 1 T2794 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /_fi/
[junit4:junit4]   2> 3 T2794 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-1362111372604
[junit4:junit4]   2> 3 T2794 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T2795 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 4 T2795 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 4 T2795 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 5 T2795 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 5 T2795 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 5 T2795 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-1362111372604/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 104 T2794 oasc.ZkTestServer.run start zk server on port:34139
[junit4:junit4]   2> 105 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@c48ea2
[junit4:junit4]   2> 106 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T2800 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 107 T2800 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 107 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38068
[junit4:junit4]   2> 107 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38068
[junit4:junit4]   2> 108 T2798 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 141 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e0000 with negotiated timeout 10000 for client /127.0.0.1:38068
[junit4:junit4]   2> 141 T2800 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e0000, negotiated timeout = 10000
[junit4:junit4]   2> 142 T2801 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c48ea2 name:ZooKeeperConnection Watcher:127.0.0.1:34139 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 142 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 143 T2794 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 145 T2799 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d242a1d3e0000
[junit4:junit4]   2> 146 T2801 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 147 T2796 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:38068 which had sessionid 0x13d242a1d3e0000
[junit4:junit4]   2> 146 T2794 oaz.ZooKeeper.close Session: 0x13d242a1d3e0000 closed
[junit4:junit4]   2> 147 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@9681
[junit4:junit4]   2> 149 T2802 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 149 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 150 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38069
[junit4:junit4]   2> 150 T2802 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 150 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38069
[junit4:junit4]   2> 151 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e0001 with negotiated timeout 10000 for client /127.0.0.1:38069
[junit4:junit4]   2> 151 T2802 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e0001, negotiated timeout = 10000
[junit4:junit4]   2> 152 T2803 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9681 name:ZooKeeperConnection Watcher:127.0.0.1:34139/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 152 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 152 T2794 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 155 T2794 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 157 T2794 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 160 T2794 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 162 T2794 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> 163 T2794 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 167 T2794 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> 168 T2794 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 172 T2794 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> 173 T2794 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 175 T2794 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> 176 T2794 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 178 T2794 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> 179 T2794 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 182 T2794 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> 183 T2794 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 186 T2794 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> 186 T2794 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 189 T2794 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> 190 T2794 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 193 T2794 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> 193 T2794 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 196 T2799 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d242a1d3e0001
[junit4:junit4]   2> 197 T2803 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 197 T2796 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:38069 which had sessionid 0x13d242a1d3e0001
[junit4:junit4]   2> 197 T2794 oaz.ZooKeeper.close Session: 0x13d242a1d3e0001 closed
[junit4:junit4]   2> 262 T2794 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 269 T2794 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60973
[junit4:junit4]   2> 270 T2794 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 270 T2794 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 270 T2794 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-1362111372799
[junit4:junit4]   2> 271 T2794 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-1362111372799/solr.xml
[junit4:junit4]   2> 271 T2794 oasc.CoreContainer.<init> New CoreContainer 12848514
[junit4:junit4]   2> 271 T2794 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-1362111372799/'
[junit4:junit4]   2> 272 T2794 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-1362111372799/'
[junit4:junit4]   2> 285 T2794 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 285 T2794 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 286 T2794 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 286 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 286 T2794 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 287 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 287 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 287 T2794 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 288 T2794 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 288 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 293 T2794 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 301 T2794 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:34139/solr
[junit4:junit4]   2> 302 T2794 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 302 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@10a12aa
[junit4:junit4]   2> 303 T2813 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 303 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 303 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38071
[junit4:junit4]   2> 303 T2813 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 304 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38071
[junit4:junit4]   2> 304 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e0002 with negotiated timeout 20000 for client /127.0.0.1:38071
[junit4:junit4]   2> 304 T2813 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e0002, negotiated timeout = 20000
[junit4:junit4]   2> 304 T2814 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10a12aa name:ZooKeeperConnection Watcher:127.0.0.1:34139 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 305 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 305 T2799 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d242a1d3e0002
[junit4:junit4]   2> 306 T2796 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:38071 which had sessionid 0x13d242a1d3e0002
[junit4:junit4]   2> 306 T2794 oaz.ZooKeeper.close Session: 0x13d242a1d3e0002 closed
[junit4:junit4]   2> 306 T2814 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 306 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 308 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@105d492
[junit4:junit4]   2> 309 T2815 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 309 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 309 T2815 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 309 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38072
[junit4:junit4]   2> 309 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38072
[junit4:junit4]   2> 310 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e0003 with negotiated timeout 20000 for client /127.0.0.1:38072
[junit4:junit4]   2> 310 T2815 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e0003, negotiated timeout = 20000
[junit4:junit4]   2> 310 T2816 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@105d492 name:ZooKeeperConnection Watcher:127.0.0.1:34139/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 310 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 311 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 312 T2794 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 313 T2794 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60973__fi
[junit4:junit4]   2> 313 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60973__fi Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60973__fi
[junit4:junit4]   2> 314 T2794 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60973__fi
[junit4:junit4]   2> 315 T2794 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 317 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 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> 317 T2794 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 318 T2794 oasc.Overseer.start Overseer (id=89267330915106819-127.0.0.1:60973__fi-n_0000000000) starting
[junit4:junit4]   2> 319 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 319 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 320 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 320 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 320 T2818 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 321 T2794 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 322 T2794 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 323 T2817 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 325 T2819 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-1362111372799/collection1
[junit4:junit4]   2> 325 T2819 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 325 T2819 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 326 T2819 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 326 T2819 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-1362111372799/collection1/'
[junit4:junit4]   2> 326 T2819 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-1362111372799/collection1/lib/README' to classloader
[junit4:junit4]   2> 327 T2819 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-1362111372799/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 364 T2819 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 391 T2819 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 392 T2819 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 395 T2819 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 737 T2819 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 742 T2819 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 754 T2819 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 765 T2819 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 768 T2819 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 770 T2819 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 771 T2819 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 772 T2819 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-1362111372799/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/control/data/
[junit4:junit4]   2> 772 T2819 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df9cf4
[junit4:junit4]   2> 772 T2819 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 772 T2819 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-1362111372604/control/data forceNew:false
[junit4:junit4]   2> 773 T2819 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-1362111372604/control/data
[junit4:junit4]   2> 773 T2819 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/control/data/index/
[junit4:junit4]   2> 773 T2819 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 773 T2819 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-1362111372604/control/data/index forceNew:false
[junit4:junit4]   2> 776 T2819 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 776 T2819 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 777 T2819 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-1362111372604/control/data/index
[junit4:junit4]   2> 778 T2819 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 778 T2819 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 778 T2819 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 778 T2819 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 779 T2819 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 779 T2819 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 779 T2819 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 779 T2819 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 779 T2819 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 781 T2819 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 783 T2819 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-1362111372604/control/data
[junit4:junit4]   2> 784 T2819 oass.SolrIndexSearcher.<init> Opening Searcher@28fc32 main
[junit4:junit4]   2> 784 T2819 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 784 T2819 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 786 T2819 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-1362111372604/control/data
[junit4:junit4]   2> 787 T2820 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@28fc32 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 787 T2819 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 788 T2819 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 788 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 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> 1826 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1827 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60973/_fi",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:60973__fi",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection"}
[junit4:junit4]   2> 1828 T2817 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 1828 T2817 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 1828 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 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> 1833 T2816 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> 2791 T2819 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2792 T2819 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60973/_fi collection:control_collection shard:shard1
[junit4:junit4]   2> 2793 T2819 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 2799 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 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> 2800 T2819 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2800 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2803 T2819 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2803 T2819 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2803 T2819 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60973/_fi/collection1/
[junit4:junit4]   2> 2804 T2819 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2804 T2819 oasc.SyncStrategy.syncToMe http://127.0.0.1:60973/_fi/collection1/ has no replicas
[junit4:junit4]   2> 2804 T2819 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60973/_fi/collection1/
[junit4:junit4]   2> 2804 T2819 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 2809 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3337 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3344 T2816 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> 3362 T2819 oasc.ZkController.register We are http://127.0.0.1:60973/_fi/collection1/ and leader is http://127.0.0.1:60973/_fi/collection1/
[junit4:junit4]   2> 3362 T2819 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60973/_fi
[junit4:junit4]   2> 3363 T2819 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3363 T2819 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 3363 T2819 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3365 T2819 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3366 T2794 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3367 T2794 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3368 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3373 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 3374 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@14ef641
[junit4:junit4]   2> 3375 T2821 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3375 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3376 T2821 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 3376 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38073
[junit4:junit4]   2> 3376 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38073
[junit4:junit4]   2> 3377 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e0004 with negotiated timeout 10000 for client /127.0.0.1:38073
[junit4:junit4]   2> 3377 T2821 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e0004, negotiated timeout = 10000
[junit4:junit4]   2> 3377 T2822 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14ef641 name:ZooKeeperConnection Watcher:127.0.0.1:34139/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3378 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3379 T2794 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3381 T2794 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 3447 T2794 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 3449 T2794 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:44273
[junit4:junit4]   2> 3449 T2794 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3450 T2794 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3450 T2794 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-1362111375982
[junit4:junit4]   2> 3450 T2794 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-1362111375982/solr.xml
[junit4:junit4]   2> 3451 T2794 oasc.CoreContainer.<init> New CoreContainer 32181429
[junit4:junit4]   2> 3451 T2794 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-1362111375982/'
[junit4:junit4]   2> 3451 T2794 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-1362111375982/'
[junit4:junit4]   2> 3464 T2794 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 3465 T2794 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3465 T2794 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 3465 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3466 T2794 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3466 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3466 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3466 T2794 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3467 T2794 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3467 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 3472 T2794 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3480 T2794 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:34139/solr
[junit4:junit4]   2> 3480 T2794 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 3481 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@12cfeea
[junit4:junit4]   2> 3481 T2832 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3481 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3482 T2832 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 3482 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38074
[junit4:junit4]   2> 3482 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38074
[junit4:junit4]   2> 3483 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e0005 with negotiated timeout 20000 for client /127.0.0.1:38074
[junit4:junit4]   2> 3483 T2832 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e0005, negotiated timeout = 20000
[junit4:junit4]   2> 3483 T2833 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12cfeea name:ZooKeeperConnection Watcher:127.0.0.1:34139 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3483 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3484 T2799 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d242a1d3e0005
[junit4:junit4]   2> 3484 T2833 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 3484 T2796 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:38074 which had sessionid 0x13d242a1d3e0005
[junit4:junit4]   2> 3484 T2794 oaz.ZooKeeper.close Session: 0x13d242a1d3e0005 closed
[junit4:junit4]   2> 3485 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 3487 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@701493
[junit4:junit4]   2> 3487 T2834 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3487 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3488 T2834 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 3488 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38075
[junit4:junit4]   2> 3488 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38075
[junit4:junit4]   2> 3488 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e0006 with negotiated timeout 20000 for client /127.0.0.1:38075
[junit4:junit4]   2> 3488 T2834 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e0006, negotiated timeout = 20000
[junit4:junit4]   2> 3489 T2835 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@701493 name:ZooKeeperConnection Watcher:127.0.0.1:34139/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3489 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3489 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3490 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3491 T2794 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4492 T2794 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44273__fi
[junit4:junit4]   2> 4493 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0006 type:delete cxid:0x9 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:44273__fi Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:44273__fi
[junit4:junit4]   2> 4494 T2794 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44273__fi
[junit4:junit4]   2> 4495 T2822 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4495 T2816 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4495 T2835 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4496 T2816 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> 4499 T2836 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-1362111375982/collection1
[junit4:junit4]   2> 4500 T2836 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4500 T2836 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4500 T2836 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4501 T2836 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-1362111375982/collection1/'
[junit4:junit4]   2> 4501 T2836 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-1362111375982/collection1/lib/README' to classloader
[junit4:junit4]   2> 4501 T2836 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-1362111375982/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4520 T2836 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 4556 T2836 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4556 T2836 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4560 T2836 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4848 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4848 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60973/_fi",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:60973__fi",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection"}
[junit4:junit4]   2> 4850 T2816 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> 4850 T2835 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> 4850 T2822 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> 4873 T2836 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4877 T2836 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4879 T2836 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4886 T2836 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4888 T2836 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4890 T2836 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4891 T2836 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4892 T2836 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-1362111375982/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty1/
[junit4:junit4]   2> 4892 T2836 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df9cf4
[junit4:junit4]   2> 4892 T2836 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 4892 T2836 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-1362111372604/jetty1 forceNew:false
[junit4:junit4]   2> 4893 T2836 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-1362111372604/jetty1
[junit4:junit4]   2> 4893 T2836 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty1/index/
[junit4:junit4]   2> 4893 T2836 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4893 T2836 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-1362111372604/jetty1/index forceNew:false
[junit4:junit4]   2> 4896 T2836 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4896 T2836 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 4897 T2836 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-1362111372604/jetty1/index
[junit4:junit4]   2> 4898 T2836 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4898 T2836 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4898 T2836 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4898 T2836 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4899 T2836 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4899 T2836 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4899 T2836 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4899 T2836 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4899 T2836 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4901 T2836 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4903 T2836 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-1362111372604/jetty1
[junit4:junit4]   2> 4904 T2836 oass.SolrIndexSearcher.<init> Opening Searcher@b6d7b7 main
[junit4:junit4]   2> 4904 T2836 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4904 T2836 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4906 T2837 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b6d7b7 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4907 T2836 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 4907 T2836 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6352 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6353 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:44273/_fi",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:44273__fi",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 6353 T2817 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 6354 T2817 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 6357 T2816 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> 6357 T2835 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> 6357 T2822 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> 6908 T2836 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 6908 T2836 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:44273/_fi collection:collection1 shard:shard1
[junit4:junit4]   2> 6909 T2836 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 6914 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0006 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> 6914 T2836 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 6915 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0006 type:create cxid:0x47 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6916 T2836 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 6917 T2836 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 6917 T2836 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:44273/_fi/collection1/
[junit4:junit4]   2> 6917 T2836 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 6917 T2836 oasc.SyncStrategy.syncToMe http://127.0.0.1:44273/_fi/collection1/ has no replicas
[junit4:junit4]   2> 6918 T2836 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:44273/_fi/collection1/
[junit4:junit4]   2> 6918 T2836 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 6921 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0006 type:create cxid:0x51 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7861 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7866 T2816 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> 7866 T2822 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> 7866 T2835 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> 7875 T2836 oasc.ZkController.register We are http://127.0.0.1:44273/_fi/collection1/ and leader is http://127.0.0.1:44273/_fi/collection1/
[junit4:junit4]   2> 7875 T2836 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:44273/_fi
[junit4:junit4]   2> 7876 T2836 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 7876 T2836 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 7876 T2836 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7878 T2836 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7879 T2794 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 7879 T2794 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7880 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7964 T2794 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 7966 T2794 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:34586
[junit4:junit4]   2> 7967 T2794 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 7967 T2794 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7968 T2794 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-1362111380485
[junit4:junit4]   2> 7968 T2794 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-1362111380485/solr.xml
[junit4:junit4]   2> 7968 T2794 oasc.CoreContainer.<init> New CoreContainer 33308311
[junit4:junit4]   2> 7969 T2794 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-1362111380485/'
[junit4:junit4]   2> 7969 T2794 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-1362111380485/'
[junit4:junit4]   2> 7998 T2794 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 7998 T2794 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 7999 T2794 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 7999 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 7999 T2794 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8000 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8000 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8001 T2794 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8001 T2794 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8001 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 8007 T2794 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8018 T2794 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:34139/solr
[junit4:junit4]   2> 8019 T2794 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 8019 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@16b50c7
[junit4:junit4]   2> 8020 T2847 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8021 T2847 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 8021 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38092
[junit4:junit4]   2> 8020 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8021 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38092
[junit4:junit4]   2> 8022 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e0007 with negotiated timeout 20000 for client /127.0.0.1:38092
[junit4:junit4]   2> 8023 T2847 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e0007, negotiated timeout = 20000
[junit4:junit4]   2> 8024 T2848 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16b50c7 name:ZooKeeperConnection Watcher:127.0.0.1:34139 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8025 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8026 T2799 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d242a1d3e0007
[junit4:junit4]   2> 8027 T2796 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:38092 which had sessionid 0x13d242a1d3e0007
[junit4:junit4]   2> 8027 T2848 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 8027 T2794 oaz.ZooKeeper.close Session: 0x13d242a1d3e0007 closed
[junit4:junit4]   2> 8028 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 8032 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1fefb0e
[junit4:junit4]   2> 8033 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8034 T2849 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8034 T2849 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 8034 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38093
[junit4:junit4]   2> 8034 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38093
[junit4:junit4]   2> 8035 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e0008 with negotiated timeout 20000 for client /127.0.0.1:38093
[junit4:junit4]   2> 8035 T2849 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e0008, negotiated timeout = 20000
[junit4:junit4]   2> 8036 T2850 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1fefb0e name:ZooKeeperConnection Watcher:127.0.0.1:34139/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8036 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8037 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8039 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8041 T2794 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 9047 T2794 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34586__fi
[junit4:junit4]   2> 9047 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0008 type:delete cxid:0x9 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:34586__fi Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:34586__fi
[junit4:junit4]   2> 9048 T2794 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34586__fi
[junit4:junit4]   2> 9050 T2816 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> 9050 T2835 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9050 T2850 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9050 T2822 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9050 T2835 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> 9050 T2822 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> 9051 T2816 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9058 T2851 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-1362111380485/collection1
[junit4:junit4]   2> 9058 T2851 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 9059 T2851 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 9059 T2851 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 9061 T2851 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-1362111380485/collection1/'
[junit4:junit4]   2> 9063 T2851 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-1362111380485/collection1/lib/README' to classloader
[junit4:junit4]   2> 9063 T2851 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-1362111380485/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 9114 T2851 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 9171 T2851 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9172 T2851 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9177 T2851 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9370 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9371 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:44273/_fi",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:44273__fi",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 9374 T2822 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> 9374 T2835 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> 9374 T2850 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> 9374 T2816 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> 9701 T2851 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9709 T2851 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9713 T2851 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9736 T2851 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9739 T2851 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9742 T2851 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9743 T2851 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9743 T2851 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-1362111380485/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty2/
[junit4:junit4]   2> 9744 T2851 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df9cf4
[junit4:junit4]   2> 9744 T2851 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 9744 T2851 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-1362111372604/jetty2 forceNew:false
[junit4:junit4]   2> 9744 T2851 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-1362111372604/jetty2
[junit4:junit4]   2> 9745 T2851 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty2/index/
[junit4:junit4]   2> 9745 T2851 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 9745 T2851 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-1362111372604/jetty2/index forceNew:false
[junit4:junit4]   2> 9748 T2851 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9748 T2851 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 9749 T2851 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-1362111372604/jetty2/index
[junit4:junit4]   2> 9751 T2851 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 9751 T2851 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 9751 T2851 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 9751 T2851 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9752 T2851 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9752 T2851 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 9752 T2851 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 9752 T2851 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9753 T2851 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9755 T2851 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9758 T2851 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-1362111372604/jetty2
[junit4:junit4]   2> 9758 T2851 oass.SolrIndexSearcher.<init> Opening Searcher@e743a main
[junit4:junit4]   2> 9759 T2851 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9759 T2851 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9762 T2852 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e743a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9762 T2851 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 9762 T2851 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10877 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10878 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34586/_fi",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34586__fi",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 10878 T2817 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 10878 T2817 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 10881 T2835 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> 10881 T2850 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> 10881 T2822 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> 10881 T2816 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> 11764 T2851 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 11764 T2851 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:34586/_fi collection:collection1 shard:shard2
[junit4:junit4]   2> 11765 T2851 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 11773 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0008 type:delete cxid:0x45 zxid:0x7e txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> 11774 T2851 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 11774 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0008 type:create cxid:0x46 zxid:0x7f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11775 T2851 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 11775 T2851 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 11775 T2851 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:34586/_fi/collection1/
[junit4:junit4]   2> 11776 T2851 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 11776 T2851 oasc.SyncStrategy.syncToMe http://127.0.0.1:34586/_fi/collection1/ has no replicas
[junit4:junit4]   2> 11776 T2851 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:34586/_fi/collection1/
[junit4:junit4]   2> 11776 T2851 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 11778 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0008 type:create cxid:0x4f zxid:0x82 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12383 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12388 T2835 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> 12388 T2850 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> 12388 T2816 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> 12388 T2822 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> 12432 T2851 oasc.ZkController.register We are http://127.0.0.1:34586/_fi/collection1/ and leader is http://127.0.0.1:34586/_fi/collection1/
[junit4:junit4]   2> 12432 T2851 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34586/_fi
[junit4:junit4]   2> 12432 T2851 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 12433 T2851 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 12433 T2851 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12450 T2851 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12451 T2794 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 12452 T2794 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 12452 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12506 T2794 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 12508 T2794 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:34220
[junit4:junit4]   2> 12508 T2794 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 12509 T2794 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 12509 T2794 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-1362111385056
[junit4:junit4]   2> 12509 T2794 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-1362111385056/solr.xml
[junit4:junit4]   2> 12510 T2794 oasc.CoreContainer.<init> New CoreContainer 5173191
[junit4:junit4]   2> 12510 T2794 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-1362111385056/'
[junit4:junit4]   2> 12510 T2794 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-1362111385056/'
[junit4:junit4]   2> 12525 T2794 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 12525 T2794 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 12525 T2794 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 12526 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 12526 T2794 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 12526 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 12527 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 12527 T2794 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 12527 T2794 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 12528 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 12533 T2794 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 12542 T2794 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:34139/solr
[junit4:junit4]   2> 12543 T2794 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 12543 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@a9997a
[junit4:junit4]   2> 12544 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12544 T2862 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12544 T2862 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 12544 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38099
[junit4:junit4]   2> 12545 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38099
[junit4:junit4]   2> 12546 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e0009 with negotiated timeout 20000 for client /127.0.0.1:38099
[junit4:junit4]   2> 12546 T2862 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e0009, negotiated timeout = 20000
[junit4:junit4]   2> 12546 T2863 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a9997a name:ZooKeeperConnection Watcher:127.0.0.1:34139 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12547 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12547 T2799 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d242a1d3e0009
[junit4:junit4]   2> 12548 T2863 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 12548 T2796 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:38099 which had sessionid 0x13d242a1d3e0009
[junit4:junit4]   2> 12548 T2794 oaz.ZooKeeper.close Session: 0x13d242a1d3e0009 closed
[junit4:junit4]   2> 12548 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 12551 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1705f95
[junit4:junit4]   2> 12551 T2864 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12551 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12552 T2864 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 12552 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38100
[junit4:junit4]   2> 12552 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38100
[junit4:junit4]   2> 12553 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e000a with negotiated timeout 20000 for client /127.0.0.1:38100
[junit4:junit4]   2> 12553 T2864 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e000a, negotiated timeout = 20000
[junit4:junit4]   2> 12553 T2865 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1705f95 name:ZooKeeperConnection Watcher:127.0.0.1:34139/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12553 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12553 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e000a type:create cxid:0x1 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12554 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e000a type:create cxid:0x2 zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12555 T2794 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 13556 T2794 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34220__fi
[junit4:junit4]   2> 13557 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e000a type:delete cxid:0x9 zxid:0x91 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:34220__fi Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:34220__fi
[junit4:junit4]   2> 13558 T2794 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34220__fi
[junit4:junit4]   2> 13559 T2850 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> 13559 T2816 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> 13560 T2822 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> 13560 T2835 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13560 T2865 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13560 T2835 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> 13561 T2850 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13561 T2816 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13561 T2822 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13564 T2866 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-1362111385056/collection1
[junit4:junit4]   2> 13565 T2866 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 13565 T2866 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 13565 T2866 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 13566 T2866 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-1362111385056/collection1/'
[junit4:junit4]   2> 13567 T2866 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-1362111385056/collection1/lib/README' to classloader
[junit4:junit4]   2> 13567 T2866 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-1362111385056/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 13602 T2866 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 13630 T2866 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 13632 T2866 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 13636 T2866 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 13891 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13892 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34586/_fi",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34586__fi",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 13893 T2835 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> 13894 T2822 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> 13894 T2865 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> 13894 T2816 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> 13894 T2850 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> 13980 T2866 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 13985 T2866 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 13987 T2866 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 14006 T2866 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 14008 T2866 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 14010 T2866 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 14011 T2866 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 14012 T2866 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-1362111385056/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty3/
[junit4:junit4]   2> 14012 T2866 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df9cf4
[junit4:junit4]   2> 14012 T2866 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 14013 T2866 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-1362111372604/jetty3 forceNew:false
[junit4:junit4]   2> 14013 T2866 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-1362111372604/jetty3
[junit4:junit4]   2> 14013 T2866 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty3/index/
[junit4:junit4]   2> 14013 T2866 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 14014 T2866 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-1362111372604/jetty3/index forceNew:false
[junit4:junit4]   2> 14016 T2866 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14016 T2866 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 14017 T2866 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-1362111372604/jetty3/index
[junit4:junit4]   2> 14018 T2866 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 14018 T2866 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 14019 T2866 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 14019 T2866 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 14019 T2866 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 14020 T2866 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 14020 T2866 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 14020 T2866 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 14020 T2866 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 14023 T2866 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 14025 T2866 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-1362111372604/jetty3
[junit4:junit4]   2> 14025 T2866 oass.SolrIndexSearcher.<init> Opening Searcher@106586 main
[junit4:junit4]   2> 14026 T2866 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 14026 T2866 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 14028 T2866 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-1362111372604/jetty3
[junit4:junit4]   2> 14029 T2867 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@106586 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 14029 T2866 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 14030 T2866 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 15398 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15399 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34220/_fi",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34220__fi",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 15400 T2817 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 15400 T2817 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 15405 T2822 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> 15405 T2816 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> 15405 T2835 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> 15405 T2865 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> 15405 T2850 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> 16031 T2866 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 16031 T2866 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:34220/_fi collection:collection1 shard:shard1
[junit4:junit4]   2> 16033 T2866 oasc.ZkController.register We are http://127.0.0.1:34220/_fi/collection1/ and leader is http://127.0.0.1:44273/_fi/collection1/
[junit4:junit4]   2> 16033 T2866 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34220/_fi
[junit4:junit4]   2> 16033 T2866 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 16034 T2866 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C183 name=collection1 org.apache.solr.core.SolrCore@ec9d78 url=http://127.0.0.1:34220/_fi/collection1 node=127.0.0.1:34220__fi C183_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:34220/_fi, node_name=127.0.0.1:34220__fi, state=down, core=collection1, collection=collection1}
[junit4:junit4]   2> 16034 T2868 C183 P34220 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 16034 T2866 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16034 T2868 C183 P34220 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 16034 T2868 C183 P34220 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 16035 T2868 C183 P34220 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16035 T2794 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 16035 T2794 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 16036 T2868 C183 P34220 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16036 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16091 T2794 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 16095 T2794 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:48975
[junit4:junit4]   2> 16095 T2794 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 16096 T2794 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 16096 T2794 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-1362111388639
[junit4:junit4]   2> 16096 T2794 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-1362111388639/solr.xml
[junit4:junit4]   2> 16097 T2794 oasc.CoreContainer.<init> New CoreContainer 11146644
[junit4:junit4]   2> 16097 T2794 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-1362111388639/'
[junit4:junit4]   2> 16097 T2794 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-1362111388639/'
[junit4:junit4]   2> 16119 T2794 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 16120 T2794 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 16121 T2794 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 16121 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 16121 T2794 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 16122 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 16122 T2794 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 16123 T2794 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 16123 T2794 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 16124 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 16133 T2794 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 16146 T2794 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:34139/solr
[junit4:junit4]   2> 16147 T2794 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 16147 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@a69e38
[junit4:junit4]   2> 16149 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 16149 T2878 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 16150 T2878 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 16150 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38103
[junit4:junit4]   2> 16150 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38103
[junit4:junit4]   2> 16151 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e000b with negotiated timeout 20000 for client /127.0.0.1:38103
[junit4:junit4]   2> 16151 T2878 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e000b, negotiated timeout = 20000
[junit4:junit4]   2> 16151 T2879 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a69e38 name:ZooKeeperConnection Watcher:127.0.0.1:34139 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16151 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 16152 T2799 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d242a1d3e000b
[junit4:junit4]   2> 16153 T2796 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:38103 which had sessionid 0x13d242a1d3e000b
[junit4:junit4]   2> 16153 T2794 oaz.ZooKeeper.close Session: 0x13d242a1d3e000b closed
[junit4:junit4]   2> 16153 T2879 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 16153 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 16156 T2794 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:34139/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@11ea4e
[junit4:junit4]   2> 16157 T2794 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 16158 T2880 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:34139. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 16158 T2796 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:38104
[junit4:junit4]   2> 16158 T2880 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:34139, initiating session
[junit4:junit4]   2> 16159 T2796 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:38104
[junit4:junit4]   2> 16159 T2798 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d242a1d3e000c with negotiated timeout 20000 for client /127.0.0.1:38104
[junit4:junit4]   2> 16159 T2880 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:34139, sessionid = 0x13d242a1d3e000c, negotiated timeout = 20000
[junit4:junit4]   2> 16160 T2881 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11ea4e name:ZooKeeperConnection Watcher:127.0.0.1:34139/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16160 T2794 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 16161 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e000c type:create cxid:0x1 zxid:0xa2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16161 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e000c type:create cxid:0x2 zxid:0xa3 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16162 T2794 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 16909 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16910 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34220/_fi",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34220__fi",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 16913 T2850 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> 16913 T2865 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> 16913 T2816 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> 16913 T2881 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> 16913 T2822 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> 16913 T2835 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> 17165 T2794 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48975__fi
[junit4:junit4]   2> 17165 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e000c type:delete cxid:0xb zxid:0xa8 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:48975__fi Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:48975__fi
[junit4:junit4]   2> 17166 T2794 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48975__fi
[junit4:junit4]   2> 17167 T2816 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> 17167 T2822 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> 17167 T2881 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> 17168 T2865 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17168 T2850 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17168 T2835 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17168 T2850 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> 17168 T2865 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> 17169 T2816 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17169 T2835 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> 17169 T2822 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17170 T2881 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17174 T2882 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-1362111388639/collection1
[junit4:junit4]   2> 17174 T2882 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 17175 T2882 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 17175 T2882 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 17176 T2882 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-1362111388639/collection1/'
[junit4:junit4]   2> 17176 T2882 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-1362111388639/collection1/lib/README' to classloader
[junit4:junit4]   2> 17176 T2882 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-1362111388639/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 17201 T2882 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 17245 T2882 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 17245 T2882 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 17249 T2882 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 17592 T2882 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 17598 T2882 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 17600 T2882 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 17606 T2882 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17609 T2882 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17611 T2882 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17613 T2882 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17614 T2882 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-1362111388639/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty4/
[junit4:junit4]   2> 17614 T2882 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df9cf4
[junit4:junit4]   2> 17615 T2882 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 17615 T2882 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-1362111372604/jetty4 forceNew:false
[junit4:junit4]   2> 17615 T2882 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-1362111372604/jetty4
[junit4:junit4]   2> 17615 T2882 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty4/index/
[junit4:junit4]   2> 17616 T2882 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 17616 T2882 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-1362111372604/jetty4/index forceNew:false
[junit4:junit4]   2> 17618 T2882 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty4/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 17619 T2882 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 17619 T2882 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-1362111372604/jetty4/index
[junit4:junit4]   2> 17620 T2882 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 17620 T2882 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 17620 T2882 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 17621 T2882 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 17621 T2882 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 17621 T2882 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 17621 T2882 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 17621 T2882 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 17622 T2882 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 17624 T2882 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 17626 T2882 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-1362111372604/jetty4
[junit4:junit4]   2> 17627 T2882 oass.SolrIndexSearcher.<init> Opening Searcher@62ced0 main
[junit4:junit4]   2> 17627 T2882 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 17628 T2882 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 17632 T2883 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62ced0 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 17634 T2882 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 17634 T2882 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 18417 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18418 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48975/_fi",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48975__fi",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 18418 T2817 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 18418 T2817 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 18423 T2816 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> 18423 T2835 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> 18424 T2881 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> 18423 T2822 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> 18423 T2850 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> 18423 T2865 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> 18635 T2882 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 18636 T2882 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:48975/_fi collection:collection1 shard:shard2
[junit4:junit4]   2> 18639 T2882 oasc.ZkController.register We are http://127.0.0.1:48975/_fi/collection1/ and leader is http://127.0.0.1:34586/_fi/collection1/
[junit4:junit4]   2> 18639 T2882 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48975/_fi
[junit4:junit4]   2> 18640 T2882 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 18640 T2882 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C184 name=collection1 org.apache.solr.core.SolrCore@129584 url=http://127.0.0.1:48975/_fi/collection1 node=127.0.0.1:48975__fi C184_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:48975/_fi, node_name=127.0.0.1:48975__fi, state=down, core=collection1, collection=collection1}
[junit4:junit4]   2> 18640 T2884 C184 P48975 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 18641 T2882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18641 T2884 C184 P48975 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 18642 T2884 C184 P48975 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 18642 T2884 C184 P48975 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 18643 T2794 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 18643 T2884 C184 P48975 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18643 T2794 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 18644 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18652 T2794 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18653 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18661 T2830 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-1362111375982/onenodecollectioncore
[junit4:junit4]   2> 18662 T2830 oasc.ZkController.createCollectionZkNode Check for collection zkNode:onenodecollection
[junit4:junit4]   2> 18663 T2830 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:onenodecollection
[junit4:junit4]   2> 18664 T2830 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4]   2> 18665 T2830 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4]   2> 18666 T2830 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection
[junit4:junit4]   2> 18668 T2830 oasc.ZkController.readConfigName Load collection config from:/collections/onenodecollection
[junit4:junit4]   2> 18670 T2830 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-1362111375982/onenodecollectioncore/'
[junit4:junit4]   2> 18711 T2830 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 18752 T2830 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 18753 T2830 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 18756 T2830 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2>  C183_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:34220/_fi, node_name=127.0.0.1:34220__fi, shard=shard1, state=recovering, core=collection1, collection=collection1}
[junit4:junit4]   2> 19040 T2868 C183 P34220 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:44273/_fi/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 19040 T2868 C183 P34220 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:34220/_fi START replicas=[http://127.0.0.1:44273/_fi/collection1/] nUpdates=100
[junit4:junit4]   2> 19040 T2868 C183 P34220 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 19041 T2868 C183 P34220 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 19041 T2868 C183 P34220 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 19041 T2868 C183 P34220 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 19041 T2868 C183 P34220 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 19041 T2868 C183 P34220 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:44273/_fi/collection1/. core=collection1
[junit4:junit4]   2> 19041 T2868 C183 P34220 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C185 name=collection1 org.apache.solr.core.SolrCore@a7a193 url=http://127.0.0.1:44273/_fi/collection1 node=127.0.0.1:44273__fi C185_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:44273/_fi, node_name=127.0.0.1:44273__fi, shard=shard1, state=active, core=collection1, collection=collection1, leader=true}
[junit4:junit4]   2> 19046 T2828 C185 P44273 REQ /get {distrib=false&version=2&getVersions=100&qt=/get&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 19050 T2829 C185 P44273 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 19051 T2829 C185 P44273 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-1362111372604/jetty1
[junit4:junit4]   2> 19052 T2829 C185 P44273 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 19052 T2829 C185 P44273 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 19060 T2829 C185 P44273 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty1/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19061 T2829 C185 P44273 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19061 T2829 C185 P44273 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-1362111372604/jetty1
[junit4:junit4]   2> 19061 T2829 C185 P44273 oass.SolrIndexSearcher.<init> Opening Searcher@1c84fa0 realtime
[junit4:junit4]   2> 19062 T2829 C185 P44273 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 19062 T2829 C185 P44273 /update {commit_end_point=true&version=2&wt=javabin&softCommit=false&openSearcher=false&waitSearcher=true&commit=true} {commit=} 0 13
[junit4:junit4]   2> 19063 T2868 C183 P34220 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 19063 T2868 C183 P34220 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 19066 T2829 C185 P44273 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 19066 T2829 C185 P44273 REQ /replication {version=2&qt=/replication&wt=javabin&command=indexversion} status=0 QTime=2 
[junit4:junit4]   2> 19067 T2868 C183 P34220 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 19067 T2868 C183 P34220 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 19067 T2868 C183 P34220 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 19069 T2831 C185 P44273 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-1362111372604/jetty1
[junit4:junit4]   2> 19070 T2831 C185 P44273 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-1362111372604/jetty1/index
[junit4:junit4]   2> 19070 T2831 C185 P44273 REQ /replication {version=2&qt=/replication&wt=javabin&command=filelist&generation=2} status=0 QTime=1 
[junit4:junit4]   2> 19070 T2868 C183 P34220 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 19071 T2868 C183 P34220 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-1362111372604/jetty3/index.20130301151631672 forceNew:false
[junit4:junit4]   2> 19071 T2868 C183 P34220 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-1362111372604/jetty3
[junit4:junit4]   2> 19072 T2868 C183 P34220 oash.SnapPuller.fetchLatestIndex Starting download to org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty3/index.20130301151631672 lockFactory=org.apache.lucene.store.NativeFSLockFactory@f85798 fullCopy=true
[junit4:junit4]   2> 19074 T2828 C185 P44273 REQ /replication {file=segments_2&qt=/replication&wt=filestream&checksum=true&command=filecontent&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 19075 T2868 C183 P34220 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-1362111372604/jetty3/index
[junit4:junit4]   2> 19075 T2868 C183 P34220 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 19080 T2868 C183 P34220 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130301151631672
[junit4:junit4]   2> 19081 T2868 C183 P34220 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-1362111372604/jetty3
[junit4:junit4]   2> 19082 T2868 C183 P34220 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-1362111372604/jetty3
[junit4:junit4]   2> 19082 T2868 C183 P34220 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty3/index/ new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty3/index.20130301151631672
[junit4:junit4]   2> 19084 T2868 C183 P34220 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty3/index.20130301151631672,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19084 T2868 C183 P34220 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19084 T2868 C183 P34220 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-1362111372604/jetty3
[junit4:junit4]   2> 19085 T2868 C183 P34220 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-1362111372604/jetty3
[junit4:junit4]   2> 19085 T2868 C183 P34220 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 19085 T2868 C183 P34220 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 19085 T2868 C183 P34220 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 19086 T2868 C183 P34220 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-1362111372604/jetty3/index.20130301151631672
[junit4:junit4]   2> 19086 T2868 C183 P34220 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-1362111372604/jetty3
[junit4:junit4]   2> 19088 T2868 C183 P34220 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty3/index.20130301151631672,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19088 T2868 C183 P34220 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19088 T2868 C183 P34220 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 19089 T2868 C183 P34220 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-1362111372604/jetty3
[junit4:junit4]   2> 19089 T2868 C183 P34220 oass.SolrIndexSearcher.<init> Opening Searcher@cd214c main
[junit4:junit4]   2> 19090 T2867 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@cd214c main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 19090 T2867 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-1362111372604/jetty3/index
[junit4:junit4]   2> 19090 T2868 C183 P34220 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-1362111372604/jetty3/index.20130301151631672
[junit4:junit4]   2> 19090 T2868 C183 P34220 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-1362111372604/jetty3/index
[junit4:junit4]   2> 19091 T2868 C183 P34220 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 19091 T2868 C183 P34220 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 19091 T2868 C183 P34220 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 19091 T2868 C183 P34220 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 19093 T2868 C183 P34220 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 19100 T2830 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 19118 T2830 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 19121 T2830 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 19128 T2830 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 19132 T2830 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 19134 T2830 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 19135 T2830 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 19136 T2830 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-1362111375982/onenodecollectioncore/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362111372604/onenodecollection/
[junit4:junit4]   2> 19136 T2830 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df9cf4
[junit4:junit4]   2> 19137 T2830 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 19137 T2830 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362111372604/onenodecollection forceNew:false
[junit4:junit4]   2> 19138 T2830 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362111372604/onenodecollection
[junit4:junit4]   2> 19138 T2830 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-1362111372604/onenodecollection/index/
[junit4:junit4]   2> 19138 T2830 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-1362111372604/onenodecollection/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 19139 T2830 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362111372604/onenodecollection/index forceNew:false
[junit4:junit4]   2> 19142 T2830 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/solrtest-BasicDistributedZk2Test-1362111372604/onenodecollection/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 19142 T2830 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 19142 T2830 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362111372604/onenodecollection/index
[junit4:junit4]   2> 19143 T2830 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 19143 T2830 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 19144 T2830 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 19144 T2830 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 19145 T2830 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 19145 T2830 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 19145 T2830 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 19145 T2830 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 19146 T2830 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 19149 T2830 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 19151 T2830 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362111372604/onenodecollection
[junit4:junit4]   2> 19152 T2830 oass.SolrIndexSearcher.<init> Opening Searcher@2170c main
[junit4:junit4]   2> 19152 T2830 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 19152 T2830 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 19155 T2830 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1362111372604/onenodecollection
[junit4:junit4]   2> 19155 T2887 oasc.SolrCore.registerSearcher [onenodecollectioncore] Registered new searcher Searcher@2170c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 19156 T2830 oasc.ZkController.publish publishing core=onenodecollectioncore state=down
[junit4:junit4]   2> 19927 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19928 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48975/_fi",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48975__fi",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 19931 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34220/_fi",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34220__fi",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 19935 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:44273/_fi",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:44273__fi",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "roles":"none",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"onenodecollectioncore",
[junit4:junit4]   2> 	  "collection":"onenodecollection"}
[junit4:junit4]   2> 19935 T2817 oasc.Overseer$ClusterStateUpdater.createCollection Create collection onenodecollection with numShards 1
[junit4:junit4]   2> 19935 T2817 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 19940 T2822 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> 19940 T2835 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> 19941 T2881 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> 19941 T2865 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> 19941 T2850 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> 19940 T2816 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> 20157 T2830 oasc.CoreContainer.registerCore registering core: onenodecollectioncore
[junit4:junit4]   2> 20158 T2830 oasc.ZkController.register Register replica - core:onenodecollectioncore address:http://127.0.0.1:44273/_fi collection:onenodecollection shard:shard1
[junit4:junit4]   2> 20159 T2830 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leader_elect/shard1/election
[junit4:junit4]   2> 20166 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0006 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> 20167 T2830 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 20168 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0006 type:create cxid:0xa7 zxid:0xc4 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 20170 T2830 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 20171 T2830 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 20171 T2830 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:44273/_fi/onenodecollectioncore/
[junit4:junit4]   2> 20172 T2830 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 20173 T2830 oasc.SyncStrategy.syncToMe http://127.0.0.1:44273/_fi/onenodecollectioncore/ has no replicas
[junit4:junit4]   2> 20173 T2830 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:44273/_fi/onenodecollectioncore/
[junit4:junit4]   2> 20174 T2830 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leaders/shard1
[junit4:junit4]   2> 20179 T2799 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d242a1d3e0006 type:create cxid:0xb1 zxid:0xc8 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21450 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21458 T2865 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> 21458 T2881 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> 21458 T2835 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> 21459 T2822 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> 21458 T2850 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> 21459 T2816 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> 21483 T2830 oasc.ZkController.register We are http://127.0.0.1:44273/_fi/onenodecollectioncore/ and leader is http://127.0.0.1:44273/_fi/onenodecollectioncore/
[junit4:junit4]   2> 21484 T2830 oasc.ZkController.register No LogReplay needed for core=onenodecollectioncore baseURL=http://127.0.0.1:44273/_fi
[junit4:junit4]   2> 21484 T2830 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 21485 T2830 oasc.ZkController.publish publishing core=onenodecollectioncore state=active
[junit4:junit4]   2> 21486 T2830 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21488 T2794 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: onenodecollection failOnTimeout:true timeout (sec):230
[junit4:junit4]   2> 21490 T2794 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22491 T2794 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C184_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:48975/_fi, node_name=127.0.0.1:48975__fi, shard=shard2, state=recovering, core=collection1, collection=collection1}
[junit4:junit4]   2> 22651 T2884 C184 P48975 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:34586/_fi/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 22651 T2884 C184 P48975 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:48975/_fi START replicas=[http://127.0.0.1:34586/_fi/collection1/] nUpdates=100
[junit4:junit4]   2> 22652 T2884 C184 P48975 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 22652 T2884 C184 P48975 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 22652 T2884 C184 P48975 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 22653 T2884 C184 P48975 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 22653 T2884 C184 P48975 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 22653 T2884 C184 P48975 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:34586/_fi/collection1/. core=collection1
[junit4:junit4]   2> 22654 T2884 C184 P48975 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C186 name=collection1 org.apache.solr.core.SolrCore@13c4daa url=http://127.0.0.1:34586/_fi/collection1 node=127.0.0.1:34586__fi C186_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:34586/_fi, node_name=127.0.0.1:34586__fi, shard=shard2, state=active, core=collection1, collection=collection1, leader=true}
[junit4:junit4]   2> 22661 T2843 C186 P34586 REQ /get {distrib=false&version=2&getVersions=100&qt=/get&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 22661 T2845 C186 P34586 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 22663 T2845 C186 P34586 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-1362111372604/jetty2
[junit4:junit4]   2> 22666 T2845 C186 P34586 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 22667 T2845 C186 P34586 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 22680 T2845 C186 P34586 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty2/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 22681 T2845 C186 P34586 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 22682 T2845 C186 P34586 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-1362111372604/jetty2
[junit4:junit4]   2> 22683 T2845 C186 P34586 oass.SolrIndexSearcher.<init> Opening Searcher@192f287 realtime
[junit4:junit4]   2> 22684 T2845 C186 P34586 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 22684 T2845 C186 P34586 /update {commit_end_point=true&version=2&wt=javabin&softCommit=false&openSearcher=false&waitSearcher=true&commit=true} {commit=} 0 23
[junit4:junit4]   2> 22685 T2884 C184 P48975 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 22685 T2884 C184 P48975 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 22687 T2844 C186 P34586 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 22688 T2844 C186 P34586 REQ /replication {version=2&qt=/replication&wt=javabin&command=indexversion} status=0 QTime=1 
[junit4:junit4]   2> 22689 T2884 C184 P48975 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 22689 T2884 C184 P48975 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 22689 T2884 C184 P48975 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 22691 T2843 C186 P34586 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-1362111372604/jetty2
[junit4:junit4]   2> 22692 T2843 C186 P34586 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-1362111372604/jetty2/index
[junit4:junit4]   2> 22692 T2843 C186 P34586 REQ /replication {version=2&qt=/replication&wt=javabin&command=filelist&generation=2} status=0 QTime=1 
[junit4:junit4]   2> 22693 T2884 C184 P48975 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 22694 T2884 C184 P48975 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-1362111372604/jetty4/index.20130301151635294 forceNew:false
[junit4:junit4]   2> 22694 T2884 C184 P48975 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-1362111372604/jetty4
[junit4:junit4]   2> 22695 T2884 C184 P48975 oash.SnapPuller.fetchLatestIndex Starting download to org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty4/index.20130301151635294 lockFactory=org.apache.lucene.store.NativeFSLockFactory@ab1431 fullCopy=true
[junit4:junit4]   2> 22698 T2845 C186 P34586 REQ /replication {file=segments_2&qt=/replication&wt=filestream&checksum=true&command=filecontent&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 22700 T2884 C184 P48975 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-1362111372604/jetty4/index
[junit4:junit4]   2> 22700 T2884 C184 P48975 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 22710 T2884 C184 P48975 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130301151635294
[junit4:junit4]   2> 22712 T2884 C184 P48975 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-1362111372604/jetty4
[junit4:junit4]   2> 22712 T2884 C184 P48975 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-1362111372604/jetty4
[junit4:junit4]   2> 22712 T2884 C184 P48975 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty4/index/ new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty4/index.20130301151635294
[junit4:junit4]   2> 22715 T2884 C184 P48975 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty4/index.20130301151635294,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 22716 T2884 C184 P48975 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 22717 T2884 C184 P48975 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-1362111372604/jetty4
[junit4:junit4]   2> 22718 T2884 C184 P48975 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-1362111372604/jetty4
[junit4:junit4]   2> 22718 T2884 C184 P48975 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 22718 T2884 C184 P48975 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 22719 T2884 C184 P48975 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 22719 T2884 C184 P48975 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-1362111372604/jetty4/index.20130301151635294
[junit4:junit4]   2> 22720 T2884 C184 P48975 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-1362111372604/jetty4
[junit4:junit4]   2> 22723 T2884 C184 P48975 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty4/index.20130301151635294,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 22724 T2884 C184 P48975 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 22724 T2884 C184 P48975 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 22725 T2884 C184 P48975 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-1362111372604/jetty4
[junit4:junit4]   2> 22725 T2884 C184 P48975 oass.SolrIndexSearcher.<init> Opening Searcher@a83ac5 main
[junit4:junit4]   2> 22726 T2883 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a83ac5 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 22727 T2883 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-1362111372604/jetty4/index
[junit4:junit4]   2> 22727 T2884 C184 P48975 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-1362111372604/jetty4/index.20130301151635294
[junit4:junit4]   2> 22728 T2884 C184 P48975 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-1362111372604/jetty4/index
[junit4:junit4]   2> 22728 T2884 C184 P48975 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 22728 T2884 C184 P48975 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 22728 T2884 C184 P48975 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 22729 T2884 C184 P48975 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 22731 T2884 C184 P48975 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 22964 T2817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22964 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:44273/_fi",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:44273__fi",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "roles":"none",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"onenodecollectioncore",
[junit4:junit4]   2> 	  "collection":"onenodecollection"}
[junit4:junit4]   2> 22966 T2817 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48975/_fi",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48975__fi",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 22969 T2865 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> 22969 T2835 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> 22969 T2822 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> 22969 T2816 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> 22969 T2850 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> 22969 T2881 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> 23492 T2794 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 23493 T2794 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: onenodecollection
[junit4:junit4]   2> 23493 T2794 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2>  C183_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:34220/_fi, node_name=127.0.0.1:34220__fi, shard=shard1, state=active, core=collection1, collection=collection1}
[junit4:junit4]   2> 23509 T2861 C183 P34220 /update {version=2&wt=javabin&distrib.from=http://127.0.0.1:44273/_fi/collection1/&update.distrib=FROMLEADER} {add=[1 (1428277319280099328)]} 0 1
[junit4:junit4]   2> 23510 T2831 C185 P44273 /update {version=2&wt=javabin} {add=[1 (1428277319280099328)]} 0 8
[junit4:junit4]   2> 23518 T2828 C185 P44273 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 23540 T2828 C185 P44273 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty1/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty1/index,segFN=segments_3,generation=3,filenames=[_0_NestedPulsing_0.tim, _0_TestBloomFilteredLucene41Postings_0.tim, segments_3, _0.fnm, _0_SimpleText_0.pst, _0.nvm, _0_TestBloomFilteredLucene41Postings_0.tip, _0.fdx, _0_Pulsing41_0.tip, _0.fdt, _0_NestedPulsing_0.pos, _0_TestBloomFilteredLucene41Postings_0.blm, _0_NestedPulsing_0.tip, _0_Pulsing41_0.pos, _0_TestBloomFilteredLucene41Postings_0.pos, _0_Pulsing41_0.doc, _0.nvd, _0.si, _0_TestBloomFilteredLucene41Postings_0.doc, _0_Pulsing41_0.tim, _0_NestedPulsing_0.doc]
[junit4:junit4]   2> 23541 T2828 C185 P44273 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0_NestedPulsing_0.tim, _0_TestBloomFilteredLucene41Postings_0.tim, segments_3, _0.fnm, _0_SimpleText_0.pst, _0.nvm, _0_TestBloomFilteredLucene41Postings_0.tip, _0.fdx, _0_Pulsing41_0.tip, _0.fdt, _0_NestedPulsing_0.pos, _0_TestBloomFilteredLucene41Postings_0.blm, _0_NestedPulsing_0.tip, _0_Pulsing41_0.pos, _0_TestBloomFilteredLucene41Postings_0.pos, _0_Pulsing41_0.doc, _0.nvd, _0.si, _0_TestBloomFilteredLucene41Postings_0.doc, _0_Pulsing41_0.tim, _0_NestedPulsing_0.doc]
[junit4:junit4]   2> 23543 T2828 C185 P44273 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-1362111372604/jetty1
[junit4:junit4]   2> 23545 T2828 C185 P44273 oass.SolrIndexSearcher.<init> Opening Searcher@1abfe1e main
[junit4:junit4]   2> 23545 T2828 C185 P44273 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-1362111372604/jetty1/index
[junit4:junit4]   2> 23546 T2828 C185 P44273 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23546 T2837 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1abfe1e main{StandardDirectoryReader(segments_3:3:nrt _0(4.2):C1)}
[junit4:junit4]   2> 23546 T2837 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-1362111372604/jetty1/index
[junit4:junit4]   2> 23546 T2828 C185 P44273 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:34220/_fi/collection1/, StdNode: http://127.0.0.1:34586/_fi/collection1/, StdNode: http://127.0.0.1:48975/_fi/collection1/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4]   2> 23548 T2843 C186 P34586 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 23550 T2859 C183 P34220 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2>  C184_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:48975/_fi, node_name=127.0.0.1:48975__fi, shard=shard2, state=active, core=collection1, collection=collection1}
[junit4:junit4]   2> 23553 T2875 C184 P48975 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 23560 T2843 C186 P34586 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty2/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty2/index,segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 23561 T2843 C186 P34586 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 23561 T2843 C186 P34586 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-1362111372604/jetty2
[junit4:junit4]   2> 23562 T2843 C186 P34586 oass.SolrIndexSearcher.<init> Opening Searcher@1674c96 main
[junit4:junit4]   2> 23562 T2843 C186 P34586 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-1362111372604/jetty2/index
[junit4:junit4]   2> 23562 T2843 C186 P34586 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23562 T2875 C184 P48975 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty4/index.20130301151635294,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty4/index.20130301151635294,segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 23563 T2852 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1674c96 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 23563 T2875 C184 P48975 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 23563 T2852 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-1362111372604/jetty2/index
[junit4:junit4]   2> 23563 T2843 C186 P34586 /update {commit_end_point=true&version=2&expungeDeletes=false&wt=javabin&softCommit=false&waitSearcher=true&commit=true} {commit=} 0 15
[junit4:junit4]   2> 23564 T2875 C184 P48975 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-1362111372604/jetty4
[junit4:junit4]   2> 23564 T2875 C184 P48975 oass.SolrIndexSearcher.<init> Opening Searcher@6525c6 main
[junit4:junit4]   2> 23564 T2875 C184 P48975 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23565 T2883 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6525c6 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 23565 T2883 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-1362111372604/jetty4/index.20130301151635294
[junit4:junit4]   2> 23565 T2875 C184 P48975 /update {commit_end_point=true&version=2&expungeDeletes=false&wt=javabin&softCommit=false&waitSearcher=true&commit=true} {commit=} 0 12
[junit4:junit4]   2> 23579 T2859 C183 P34220 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty3/index.20130301151631672,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1362111372604/jetty3/index.20130301151631672,segFN=segments_3,generation=3,filenames=[_0_NestedPulsing_0.tim, _0_TestBloomFilteredLucene41Postings_0.tim, segments_3, _0.fnm, _0_SimpleText_0.pst, _0.nvm, _0_TestBloomFilteredLucene41Postings_0.tip, _0.fdx, _0_Pulsing41_0.tip, _0.fdt, _0_NestedPulsing_0.pos, _0_TestBloomFilteredLucene41Postings_0.blm, _0_NestedPulsing_0.tip, _0_Pulsing41_0.pos, _0_TestBloomFilteredLucene41Postings_0.pos, _0_Pulsing41_0.doc, _0.nvd, _0.si, _0_TestBloomFilteredLucene41Postings_0.doc, _0_Pulsing41_0.tim, _0_NestedPulsing_0.doc]
[junit4:junit4]   2> 23579 T2859 C183 P34220 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0_NestedPulsing_0.tim, _0_TestBloomFilteredLucene41Postings_0.tim, segments_3, _0.fnm, _0_SimpleText_0.pst, _0.nvm, _0_TestBloomFilteredLucene41Postings_0.tip, _0.fdx, _0_Pulsing41_0.tip, _0.fdt, _0_NestedPulsing_0.pos, _0_TestBloomFilteredLucene41Postings_0.blm, _0_NestedPulsing_0.tip, _0_Pulsing41_0.pos, _0_TestBloomFilteredLucene41Postings_0.pos, _0_Pulsing41_0.doc, _0.nvd, _0.si, _0_TestBloomFilteredLucene41Postings_0.doc, _0_Pulsing41_0.tim, _0_NestedPulsing_0.doc]
[junit4:junit4]   2> 23580 T2859 C183 P34220 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-1362111372604/jetty3
[junit4:junit4]   2> 23582 T2859 C183 P34220 oass.SolrIndexSearcher.<init> Opening Searcher@1636b9b main
[junit4:junit4]   2> 23583 T2867 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1636b9b main{StandardDirectoryReader(segments_3:3:nrt _0(4.2):C1)}
[junit4:junit4]   2> 23583 T2859 C183 P34220 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23584 T2867 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-1362111372604/jetty3/index.20130301151631672
[junit4:junit4]   2> 23584 T2859 C183 P34220 /update {commit_end_point=true&version=2&expungeDeletes=false&wt=javabin&softCommit=false&waitSearcher=true&commit=true} {commit=} 0 34
[junit4:junit4]   2> 23584 T2828 C185 P44273 /update {version=2&wt=javabin&softCommit=false&waitSearcher=true&commit=true} {commit=} 0 66
[junit4:junit4]   2> 23590 T2845 C186 P34586 REQ /select {fsv=true&isShard=true&distrib=false&version=2&NOW=1362111396188&wt=javabin&start=0&fl=id,score&shard.url=127.0.0.1:34586/_fi/collection1/|127.0.0.1:48975/_fi/collection1/&rows=10&q=*:*} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 23590 T2858 C183 P34220 REQ /select {fsv=true&isShard=true&distrib=false&version=2&NOW=1362111396188&wt=javabin&start=0&fl=id,score&shard.url=127.0.0.1:44273/_fi/collection1/|127.0.0.1:34220/_fi/collection1/&rows=10&q=*:*} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 23597 T2860 C183 P34220 REQ /select {isShard=true&distrib=false&version=2&NOW=1362111396188&wt=javabin&ids=1&shard.url=127.0.0.1:44273/_fi/collection1/|127.0.0.1:34220/_fi/collection1/&q=*:*} status=0 QTime=1 
[junit4:junit4]   2> 23602 T2829 C185 P44273 REQ /select {version=2&wt=javabin&q=*:*} hits=1 status=0 QTime=15 
[junit4:junit4]   2> 23603 T2794 oascsi.HttpClientUtil.creat

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

d)
[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> 32190 T2794 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 32193 T2793 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 3 leaked thread(s).
[junit4:junit4]   2> 32288 T2865 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 32289 T2865 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> 32289 T2865 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 32289 T2865 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 32399 T2797 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {id=Pulsing41(freqCutoff=20 minBlockSize=18 maxBlockSize=121), multiDefault=PostingsFormat(name=SimpleText), timestamp=PostingsFormat(name=NestedPulsing), range_facet_si=PostingsFormat(name=SimpleText), intDefault=PostingsFormat(name=NestedPulsing), _version_=PostingsFormat(name=NestedPulsing), range_facet_sl=PostingsFormat(name=TestBloomFilteredLucene41Postings), range_facet_l=PostingsFormat(name=NestedPulsing)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=ar_EG, timezone=Antarctica/Macquarie
[junit4:junit4]   2> NOTE: Linux 3.2.0-38-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=2,free=63200048,total=209334272
[junit4:junit4]   2> NOTE: All tests run in this JVM: [DistributedQueryElevationComponentTest, SuggesterTest, ReturnFieldsTest, OutputWriterTest, TestRecovery, TestWriterPerf, LeaderElectionTest, FullSolrCloudDistribCmdsTest, BasicZkTest, SpellingQueryConverterTest, XsltUpdateRequestHandlerTest, TermVectorComponentTest, ShardRoutingCustomTest, TestReplicationHandler, SoftAutoCommitTest, UpdateParamsTest, PolyFieldTest, DisMaxRequestHandlerTest, SolrIndexConfigTest, OverseerCollectionProcessorTest, TestUpdate, TestRealTimeGet, DirectSolrSpellCheckerTest, HighlighterTest, QueryParsingTest, ResponseLogComponentTest, RecoveryZkTest, DirectUpdateHandlerTest, DOMUtilTest, TestArbitraryIndexDir, TestRemoteStreaming, LukeRequestHandlerTest, BadIndexSchemaTest, TestQuerySenderListener, TestReload, TestCSVResponseWriter, TestCodecSupport, TestTrie, FieldMutatingUpdateProcessorTest, TestValueSourceCache, TestSolr4Spatial, TestElisionMultitermQuery, TestBM25SimilarityFactory, OverseerTest, TestJmxIntegration, ZkSolrClientTest, TestPropInjectDefaults, DocumentAnalysisRequestHandlerTest, TestMergePolicyConfig, TestStressRecovery, RAMDirectoryFactoryTest, SliceStateTest, ShowFileRequestHandlerTest, TestUtils, TestCSVLoader, StatsComponentTest, SolrInfoMBeanTest, TestQueryUtils, MultiTermTest, BasicFunctionalityTest, ShardRoutingTest, PingRequestHandlerTest, SignatureUpdateProcessorFactoryTest, SolrCoreTest, DistributedSpellCheckComponentTest, TestDistributedGrouping, TestRandomFaceting, TestFiltering, TestWordDelimiterFilterFactory, DocumentBuilderTest, CurrencyFieldTest, DistanceFunctionTest, StandardRequestHandlerTest, XmlUpdateRequestHandlerTest, SuggesterWFSTTest, IndexSchemaRuntimeFieldTest, TestSolrQueryParser, TestReversedWildcardFilterFactory, RequestHandlersTest, RequiredFieldsTest, SuggesterTSTTest, CSVRequestHandlerTest, TestFoldingMultitermQuery, UpdateRequestProcessorFactoryTest, TestPropInject, NoCacheHeaderTest, TestBinaryField, TestSolrDeletionPolicy2, TestXIncludeConfig, TestLFUCache, IndexReaderFactoryTest, EchoParamsTest, TestPluginEnable, TestDFRSimilarityFactory, URLClassifyProcessorTest, OpenExchangeRatesOrgProviderTest, TestSystemIdResolver, TestLRUCache, UUIDFieldTest, SystemInfoHandlerTest, ChaosMonkeyNothingIsSafeTest, PreAnalyzedFieldTest, ZkNodePropsTest, CircularListTest, TestDocumentBuilder, TestRandomDVFaceting, TestCharFilters, TestCollationKeyRangeQueries, BasicDistributedZk2Test]
[junit4:junit4] Completed on J1 in 32.46s, 1 test, 1 failure <<< FAILURES!

[...truncated 97 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:1207: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:870: There were test failures: 258 suites, 1078 tests, 1 failure, 8 ignored (3 assumptions)

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



Mime
View raw message