lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (32bit/ibm-j9-jdk6) - Build # 2023 - Still Failing!
Date Sat, 27 Oct 2012 06:04:49 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/2023/
Java: 32bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}

1 tests failed.
FAILED:  org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch

Error Message:
Shard still reported as live in zk - 0 jetty

Stack Trace:
java.lang.AssertionError: Shard still reported as live in zk - 0 jetty
	at __randomizedtesting.SeedInfo.seed([245E951406F4B9DF:A5B81B0C71ABD9E3]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(AbstractFullDistribZkTestBase.java:1231)
	at org.apache.solr.cloud.BasicDistributedZk2Test.brindDownShardIndexSomeDocsAndRecover(BasicDistributedZk2Test.java:217)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:92)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
	at java.lang.reflect.Method.invoke(Method.java:611)
	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:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	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:45)
	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:738)




Build Log:
[...truncated 7814 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4:junit4]   2> 3 T496 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1351317161681
[junit4:junit4]   2> 4 T496 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 10 T497 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 11 T497 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 12 T497 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 105 T496 oasc.ZkTestServer.run start zk server on port:57455
[junit4:junit4]   2> 105 T496 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57455 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@64866486
[junit4:junit4]   2> 107 T502 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57455
[junit4:junit4]   2> 106 T496 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 108 T502 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:57455, initiating session
[junit4:junit4]   2> 108 T498 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:45090
[junit4:junit4]   2> 108 T498 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:45090
[junit4:junit4]   2> 109 T500 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 229 T500 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa0c786db0000 with negotiated timeout 10000 for client /127.0.0.1:45090
[junit4:junit4]   2> 230 T502 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:57455, sessionid = 0x13aa0c786db0000, negotiated timeout = 10000
[junit4:junit4]   2> 230 T503 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@64866486 name:ZooKeeperConnection Watcher:127.0.0.1:57455 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 231 T496 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 232 T496 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 235 T501 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aa0c786db0000
[junit4:junit4]   2> 236 T496 oaz.ZooKeeper.close Session: 0x13aa0c786db0000 closed
[junit4:junit4]   2> 237 T498 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45090 which had sessionid 0x13aa0c786db0000
[junit4:junit4]   2> 237 T496 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57455/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@b730b73
[junit4:junit4]   2> 238 T503 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 239 T504 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57455
[junit4:junit4]   2> 239 T496 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 240 T504 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:57455, initiating session
[junit4:junit4]   2> 240 T498 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:45091
[junit4:junit4]   2> 241 T498 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:45091
[junit4:junit4]   2> 242 T500 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa0c786db0001 with negotiated timeout 10000 for client /127.0.0.1:45091
[junit4:junit4]   2> 242 T504 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:57455, sessionid = 0x13aa0c786db0001, negotiated timeout = 10000
[junit4:junit4]   2> 243 T505 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b730b73 name:ZooKeeperConnection Watcher:127.0.0.1:57455/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 244 T496 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 244 T496 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 247 T496 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 250 T496 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 251 T496 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 254 T496 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 255 T496 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 259 T496 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 261 T496 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 264 T496 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 265 T496 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 267 T496 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 268 T496 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 270 T496 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 271 T496 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 273 T496 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 274 T496 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 276 T496 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 277 T496 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 279 T496 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 280 T496 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 286 T496 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 287 T496 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 291 T496 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 292 T496 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 294 T501 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aa0c786db0001
[junit4:junit4]   2> 295 T496 oaz.ZooKeeper.close Session: 0x13aa0c786db0001 closed
[junit4:junit4]   2> 295 T498 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45091 which had sessionid 0x13aa0c786db0001
[junit4:junit4]   2> 296 T505 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 420 T496 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 423 T496 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:39643
[junit4:junit4]   2> 425 T496 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 426 T496 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1351317161974
[junit4:junit4]   2> 427 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1351317161974/'
[junit4:junit4]   2> 443 T496 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 444 T496 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 452 T496 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1351317161974
[junit4:junit4]   2> 452 T496 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1351317161974/solr.xml
[junit4:junit4]   2> 453 T496 oasc.CoreContainer.<init> New CoreContainer 492576092
[junit4:junit4]   2> 454 T496 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1351317161974/'
[junit4:junit4]   2> 454 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1351317161974/'
[junit4:junit4]   2> 470 T496 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 481 T496 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:57455/solr
[junit4:junit4]   2> 486 T496 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57455/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@6d286d28
[junit4:junit4]   2> 487 T515 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57455
[junit4:junit4]   2> 487 T496 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 489 T515 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:57455, initiating session
[junit4:junit4]   2> 489 T498 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:45092
[junit4:junit4]   2> 490 T498 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:45092
[junit4:junit4]   2> 493 T500 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa0c786db0002 with negotiated timeout 5000 for client /127.0.0.1:45092
[junit4:junit4]   2> 493 T515 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:57455, sessionid = 0x13aa0c786db0002, negotiated timeout = 5000
[junit4:junit4]   2> 494 T516 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6d286d28 name:ZooKeeperConnection Watcher:127.0.0.1:57455/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 494 T496 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 496 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 498 T496 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 499 T496 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39643_solr
[junit4:junit4]   2> 500 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:39643_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:39643_solr
[junit4:junit4]   2> 501 T496 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:39643_solr
[junit4:junit4]   2> 503 T496 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 504 T496 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 505 T496 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 505 T496 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 506 T496 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 507 T496 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 507 T496 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 508 T496 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 509 T496 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 510 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 517 T496 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 521 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 522 T496 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 524 T496 oasc.Overseer.start Overseer (id=88559921508581378-127.0.0.1:39643_solr-n_0000000000) starting
[junit4:junit4]   2> 525 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 530 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 532 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 534 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 543 T518 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 543 T496 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 545 T496 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 548 T517 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 552 T496 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1351317161974/collection1
[junit4:junit4]   2> 552 T496 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 553 T496 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 554 T496 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 555 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1351317161974/collection1/'
[junit4:junit4]   2> 556 T496 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1351317161974/collection1/lib/README' to classloader
[junit4:junit4]   2> 557 T496 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1351317161974/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 592 T496 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 645 T496 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 646 T496 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 652 T496 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1216 T496 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1221 T496 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1225 T496 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1291 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1298 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1308 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1310 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1312 T496 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1351317161974/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data/
[junit4:junit4]   2> 1313 T496 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a197a19
[junit4:junit4]   2> 1314 T496 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 1358 T496 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data lockFactory=org.apache.lucene.store.NativeFSLockFactory@37523752; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 1359 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data forceNew:false
[junit4:junit4]   2> 1360 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data
[junit4:junit4]   2> 1360 T496 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data/index/
[junit4:junit4]   2> 1361 T496 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1362 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data/index forceNew:false
[junit4:junit4]   2> 1374 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@518b518b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1375 T496 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1376 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data/index
[junit4:junit4]   2> 1432 T496 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1436 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1437 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1438 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1439 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1441 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1442 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1443 T496 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1445 T496 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1446 T496 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1447 T496 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1449 T496 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1450 T496 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1451 T496 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1452 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1454 T496 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1455 T496 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1456 T496 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1457 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1459 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1460 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1461 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1463 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1464 T496 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1465 T496 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1467 T496 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1469 T496 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1472 T496 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1474 T496 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 1477 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data
[junit4:junit4]   2> 1480 T496 oass.SolrIndexSearcher.<init> Opening Searcher@4ee14ee1 main
[junit4:junit4]   2> 1482 T496 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1483 T496 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1484 T496 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1494 T496 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1565 T519 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ee14ee1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1578 T496 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1580 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:create cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1586 T496 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1587 T496 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:39643/solr shardId:control_shard
[junit4:junit4]   2> 1591 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 1601 T496 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1614 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:delete cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 1615 T496 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1618 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2055 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2056 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:39643/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:39643_solr",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 2058 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:create cxid:0x6d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 2065 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2120 T496 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2121 T496 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2123 T496 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:39643/solr/collection1/
[junit4:junit4]   2> 2124 T496 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2125 T496 oasc.SyncStrategy.syncToMe http://127.0.0.1:39643/solr/collection1/ has no replicas
[junit4:junit4]   2> 2126 T496 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:39643/solr/collection1/
[junit4:junit4]   2> 2127 T496 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 2135 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0002 type:create cxid:0x8c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2570 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2575 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2588 T496 oasc.ZkController.register We are http://127.0.0.1:39643/solr/collection1/ and leader is http://127.0.0.1:39643/solr/collection1/
[junit4:junit4]   2> 2589 T496 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:39643/solr
[junit4:junit4]   2> 2590 T496 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2591 T496 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2593 T496 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2595 T496 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 2596 T496 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2597 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2602 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 2607 T496 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57455/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@9680968
[junit4:junit4]   2> 2608 T520 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57455
[junit4:junit4]   2> 2608 T496 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2610 T520 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:57455, initiating session
[junit4:junit4]   2> 2610 T498 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:45093
[junit4:junit4]   2> 2611 T498 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:45093
[junit4:junit4]   2> 2612 T500 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa0c786db0003 with negotiated timeout 10000 for client /127.0.0.1:45093
[junit4:junit4]   2> 2612 T520 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:57455, sessionid = 0x13aa0c786db0003, negotiated timeout = 10000
[junit4:junit4]   2> 2613 T521 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9680968 name:ZooKeeperConnection Watcher:127.0.0.1:57455/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2613 T496 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2614 T496 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2616 T496 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 2748 T496 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2751 T496 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:47896
[junit4:junit4]   2> 2753 T496 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2754 T496 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1351317164294
[junit4:junit4]   2> 2755 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1351317164294/'
[junit4:junit4]   2> 2774 T496 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2776 T496 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2777 T496 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1351317164294
[junit4:junit4]   2> 2778 T496 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1351317164294/solr.xml
[junit4:junit4]   2> 2779 T496 oasc.CoreContainer.<init> New CoreContainer 2015328287
[junit4:junit4]   2> 2780 T496 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1351317164294/'
[junit4:junit4]   2> 2781 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1351317164294/'
[junit4:junit4]   2> 2849 T496 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2864 T496 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:57455/solr
[junit4:junit4]   2> 2865 T496 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57455/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@36a936a9
[junit4:junit4]   2> 2867 T531 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57455
[junit4:junit4]   2> 2867 T496 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2868 T531 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:57455, initiating session
[junit4:junit4]   2> 2868 T498 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:45094
[junit4:junit4]   2> 2870 T498 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:45094
[junit4:junit4]   2> 2871 T500 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa0c786db0004 with negotiated timeout 5000 for client /127.0.0.1:45094
[junit4:junit4]   2> 2871 T531 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:57455, sessionid = 0x13aa0c786db0004, negotiated timeout = 5000
[junit4:junit4]   2> 2872 T532 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@36a936a9 name:ZooKeeperConnection Watcher:127.0.0.1:57455/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2873 T496 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2874 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2878 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2879 T496 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47896_solr
[junit4:junit4]   2> 2880 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:47896_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:47896_solr
[junit4:junit4]   2> 2881 T496 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:47896_solr
[junit4:junit4]   2> 2883 T516 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2883 T521 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2884 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2884 T496 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2885 T496 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2886 T496 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2887 T496 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2887 T496 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2888 T496 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2889 T496 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2889 T496 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2890 T496 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2891 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2898 T496 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2902 T496 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1351317164294/collection1
[junit4:junit4]   2> 2903 T496 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2904 T496 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2905 T496 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2906 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1351317164294/collection1/'
[junit4:junit4]   2> 2908 T496 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1351317164294/collection1/lib/README' to classloader
[junit4:junit4]   2> 2909 T496 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1351317164294/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2953 T496 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 3006 T496 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3008 T496 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3013 T496 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3078 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3079 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:39643/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:39643_solr",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 3086 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3087 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3104 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3507 T496 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3513 T496 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3516 T496 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3581 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3589 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3596 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3598 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3600 T496 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1351317164294/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1/
[junit4:junit4]   2> 3601 T496 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a197a19
[junit4:junit4]   2> 3603 T496 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 3604 T496 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a715a71; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 3605 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1 forceNew:false
[junit4:junit4]   2> 3607 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1
[junit4:junit4]   2> 3608 T496 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1/index/
[junit4:junit4]   2> 3609 T496 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3610 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1/index forceNew:false
[junit4:junit4]   2> 3620 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@71047104; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3621 T496 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 3622 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1/index
[junit4:junit4]   2> 3623 T496 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3626 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3627 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3627 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3628 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3629 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3630 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3631 T496 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3632 T496 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3633 T496 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3634 T496 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3635 T496 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3635 T496 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3636 T496 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3637 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3638 T496 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3639 T496 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3640 T496 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3641 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3642 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3643 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3644 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3645 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3646 T496 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3648 T496 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3649 T496 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3651 T496 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3653 T496 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3655 T496 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 3658 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1
[junit4:junit4]   2> 3660 T496 oass.SolrIndexSearcher.<init> Opening Searcher@8710871 main
[junit4:junit4]   2> 3661 T496 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3662 T496 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3663 T496 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3673 T496 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3736 T533 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8710871 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3751 T496 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4092 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4093 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47896/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47896_solr",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 4094 T517 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 4099 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4100 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4100 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4256 T496 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4257 T496 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:47896/solr shardId:shard1
[junit4:junit4]   2> 4258 T496 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 4272 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0004 type:delete cxid:0x45 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 4273 T496 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 4274 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0004 type:create cxid:0x46 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4276 T496 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4277 T496 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 4278 T496 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:47896/solr/collection1/
[junit4:junit4]   2> 4279 T496 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 4280 T496 oasc.SyncStrategy.syncToMe http://127.0.0.1:47896/solr/collection1/ has no replicas
[junit4:junit4]   2> 4281 T496 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:47896/solr/collection1/
[junit4:junit4]   2> 4282 T496 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 4300 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0004 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4604 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4610 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4610 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4610 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4653 T496 oasc.ZkController.register We are http://127.0.0.1:47896/solr/collection1/ and leader is http://127.0.0.1:47896/solr/collection1/
[junit4:junit4]   2> 4654 T496 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:47896/solr
[junit4:junit4]   2> 4655 T496 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4656 T496 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4658 T496 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4659 T496 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4660 T496 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4661 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4802 T496 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4805 T496 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:45622
[junit4:junit4]   2> 4807 T496 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4808 T496 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1351317166345
[junit4:junit4]   2> 4808 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1351317166345/'
[junit4:junit4]   2> 4820 T496 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4821 T496 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4822 T496 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1351317166345
[junit4:junit4]   2> 4823 T496 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1351317166345/solr.xml
[junit4:junit4]   2> 4871 T496 oasc.CoreContainer.<init> New CoreContainer 1221740754
[junit4:junit4]   2> 4871 T496 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1351317166345/'
[junit4:junit4]   2> 4872 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1351317166345/'
[junit4:junit4]   2> 4889 T496 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4898 T496 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:57455/solr
[junit4:junit4]   2> 4899 T496 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57455/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@7d377d37
[junit4:junit4]   2> 4901 T543 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57455
[junit4:junit4]   2> 4900 T496 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4902 T543 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:57455, initiating session
[junit4:junit4]   2> 4902 T498 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:45096
[junit4:junit4]   2> 4903 T498 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:45096
[junit4:junit4]   2> 4905 T500 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa0c786db0005 with negotiated timeout 5000 for client /127.0.0.1:45096
[junit4:junit4]   2> 4905 T543 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:57455, sessionid = 0x13aa0c786db0005, negotiated timeout = 5000
[junit4:junit4]   2> 4906 T544 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7d377d37 name:ZooKeeperConnection Watcher:127.0.0.1:57455/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4906 T496 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4907 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4910 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4911 T496 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45622_solr
[junit4:junit4]   2> 4911 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:45622_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:45622_solr
[junit4:junit4]   2> 4912 T496 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:45622_solr
[junit4:junit4]   2> 4914 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4915 T532 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4915 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4916 T521 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4917 T516 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4916 T496 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4917 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4918 T496 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4919 T496 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4919 T496 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4920 T496 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4921 T496 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4921 T496 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4922 T496 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4922 T496 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4923 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4931 T496 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4937 T496 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1351317166345/collection1
[junit4:junit4]   2> 4938 T496 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4940 T496 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4941 T496 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4942 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1351317166345/collection1/'
[junit4:junit4]   2> 4943 T496 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1351317166345/collection1/lib/README' to classloader
[junit4:junit4]   2> 4944 T496 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1351317166345/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5007 T496 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5062 T496 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5063 T496 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5069 T496 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5114 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5115 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47896/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47896_solr",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 5119 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5119 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5120 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5119 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5553 T496 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5563 T496 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5568 T496 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5583 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5590 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5596 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5598 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5600 T496 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1351317166345/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2/
[junit4:junit4]   2> 5601 T496 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a197a19
[junit4:junit4]   2> 5603 T496 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 5604 T496 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@30bf30bf; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 5606 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2 forceNew:false
[junit4:junit4]   2> 5607 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2
[junit4:junit4]   2> 5608 T496 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2/index/
[junit4:junit4]   2> 5609 T496 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5611 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2/index forceNew:false
[junit4:junit4]   2> 5618 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5f135f13; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5619 T496 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5624 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2/index
[junit4:junit4]   2> 5625 T496 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5630 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5631 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5633 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5634 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5635 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5636 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5638 T496 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5639 T496 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5641 T496 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5642 T496 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5644 T496 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5645 T496 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5699 T496 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5700 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5700 T496 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5701 T496 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5702 T496 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5703 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5704 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5705 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5706 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5707 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5708 T496 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5709 T496 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5710 T496 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5712 T496 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5713 T496 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5715 T496 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 5717 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2
[junit4:junit4]   2> 5719 T496 oass.SolrIndexSearcher.<init> Opening Searcher@58235823 main
[junit4:junit4]   2> 5720 T496 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5721 T496 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5722 T496 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5732 T496 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5778 T545 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@58235823 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5802 T496 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6125 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6127 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:45622/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:45622_solr",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 6131 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6131 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6131 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6131 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6304 T496 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6305 T496 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:45622/solr shardId:shard2
[junit4:junit4]   2> 6306 T496 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 6311 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0005 type:delete cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> 6312 T496 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 6313 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0005 type:create cxid:0x45 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6315 T496 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 6316 T496 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 6317 T496 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:45622/solr/collection1/
[junit4:junit4]   2> 6318 T496 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 6319 T496 oasc.SyncStrategy.syncToMe http://127.0.0.1:45622/solr/collection1/ has no replicas
[junit4:junit4]   2> 6320 T496 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:45622/solr/collection1/
[junit4:junit4]   2> 6321 T496 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 6335 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0005 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6636 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6641 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6642 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6642 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6644 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6688 T496 oasc.ZkController.register We are http://127.0.0.1:45622/solr/collection1/ and leader is http://127.0.0.1:45622/solr/collection1/
[junit4:junit4]   2> 6689 T496 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:45622/solr
[junit4:junit4]   2> 6689 T496 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 6690 T496 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6693 T496 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6694 T496 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 6695 T496 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6696 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6810 T496 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 6812 T496 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:34672
[junit4:junit4]   2> 6814 T496 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6814 T496 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1351317168379
[junit4:junit4]   2> 6815 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1351317168379/'
[junit4:junit4]   2> 6841 T496 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6842 T496 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6843 T496 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1351317168379
[junit4:junit4]   2> 6843 T496 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1351317168379/solr.xml
[junit4:junit4]   2> 6844 T496 oasc.CoreContainer.<init> New CoreContainer 658777924
[junit4:junit4]   2> 6845 T496 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1351317168379/'
[junit4:junit4]   2> 6845 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1351317168379/'
[junit4:junit4]   2> 6861 T496 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6872 T496 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:57455/solr
[junit4:junit4]   2> 6873 T496 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57455/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@68136813
[junit4:junit4]   2> 6874 T555 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57455
[junit4:junit4]   2> 6874 T496 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6875 T555 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:57455, initiating session
[junit4:junit4]   2> 6875 T498 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:45098
[junit4:junit4]   2> 6877 T498 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:45098
[junit4:junit4]   2> 6878 T555 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:57455, sessionid = 0x13aa0c786db0006, negotiated timeout = 5000
[junit4:junit4]   2> 6878 T500 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa0c786db0006 with negotiated timeout 5000 for client /127.0.0.1:45098
[junit4:junit4]   2> 6879 T556 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@68136813 name:ZooKeeperConnection Watcher:127.0.0.1:57455/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6879 T496 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6881 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6882 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6883 T496 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34672_solr
[junit4:junit4]   2> 6884 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:34672_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:34672_solr
[junit4:junit4]   2> 6884 T496 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34672_solr
[junit4:junit4]   2> 6886 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6886 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6886 T532 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6887 T496 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6887 T521 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6886 T516 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6888 T544 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6888 T496 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6888 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6889 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6890 T496 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6891 T496 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6892 T496 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6893 T496 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6893 T496 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6894 T496 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6895 T496 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6895 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6902 T496 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6907 T496 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1351317168379/collection1
[junit4:junit4]   2> 6954 T496 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6955 T496 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6955 T496 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6956 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1351317168379/collection1/'
[junit4:junit4]   2> 6958 T496 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1351317168379/collection1/lib/README' to classloader
[junit4:junit4]   2> 6958 T496 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1351317168379/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6997 T496 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 7052 T496 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 7053 T496 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 7059 T496 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7146 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7148 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:45622/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:45622_solr",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 7163 T556 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7163 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7163 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7167 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7168 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7504 T496 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 7510 T496 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7513 T496 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7524 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7528 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7535 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7536 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7538 T496 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1351317168379/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/
[junit4:junit4]   2> 7540 T496 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a197a19
[junit4:junit4]   2> 7542 T496 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 7543 T496 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3 lockFactory=org.apache.lucene.store.NativeFSLockFactory@189a189a; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 7545 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3 forceNew:false
[junit4:junit4]   2> 7546 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3
[junit4:junit4]   2> 7547 T496 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index/
[junit4:junit4]   2> 7548 T496 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 7550 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index forceNew:false
[junit4:junit4]   2> 7557 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@34413441; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7558 T496 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 7559 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index
[junit4:junit4]   2> 7560 T496 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 7564 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7565 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 7565 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7566 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 7567 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 7568 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7569 T496 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7570 T496 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7571 T496 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7572 T496 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 7574 T496 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 7576 T496 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7577 T496 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 7577 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 7578 T496 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 7579 T496 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7580 T496 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7581 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7582 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7583 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7584 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7585 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7586 T496 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7587 T496 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 7588 T496 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 7590 T496 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7591 T496 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 7593 T496 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 7595 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3
[junit4:junit4]   2> 7596 T496 oass.SolrIndexSearcher.<init> Opening Searcher@3fee3fee main
[junit4:junit4]   2> 7598 T496 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7599 T496 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7600 T496 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 7620 T496 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 7660 T557 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3fee3fee main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7668 T496 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8167 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8169 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34672/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34672_solr",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 8174 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8174 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8174 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8174 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8174 T556 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8670 T496 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 8671 T496 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:34672/solr shardId:shard1
[junit4:junit4]   2> 8674 T496 oasc.ZkController.register We are http://127.0.0.1:34672/solr/collection1/ and leader is http://127.0.0.1:47896/solr/collection1/
[junit4:junit4]   2> 8675 T496 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34672/solr
[junit4:junit4]   2> 8675 T496 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 8676 T496 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C26 name=collection1 org.apache.solr.core.SolrCore@4e404e4 url=http://127.0.0.1:34672/solr/collection1 node=127.0.0.1:34672_solr
[junit4:junit4]   2> 8679 T558 C26 P34672 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 8680 T496 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8680 T558 C26 P34672 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 8681 T558 C26 P34672 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8681 T496 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 8682 T496 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8683 T558 C26 P34672 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:47896/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 8683 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8685 T558 C26 P34672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 8735 T558 C26 P34672 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:34672/solr START replicas=[http://127.0.0.1:47896/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 8736 T558 C26 P34672 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 8737 T558 C26 P34672 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 8737 T558 C26 P34672 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 8738 T558 C26 P34672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C27 name=collection1 org.apache.solr.core.SolrCore@49164916 url=http://127.0.0.1:47896/solr/collection1 node=127.0.0.1:47896_solr
[junit4:junit4]   2> 8748 T528 C27 P47896 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> 8848 T496 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 8851 T496 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:56086
[junit4:junit4]   2> 8852 T496 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8853 T496 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1351317170411
[junit4:junit4]   2> 8853 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1351317170411/'
[junit4:junit4]   2> 8867 T496 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8868 T496 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8869 T496 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1351317170411
[junit4:junit4]   2> 8870 T496 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1351317170411/solr.xml
[junit4:junit4]   2> 8871 T496 oasc.CoreContainer.<init> New CoreContainer 538583066
[junit4:junit4]   2> 8872 T496 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1351317170411/'
[junit4:junit4]   2> 8873 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1351317170411/'
[junit4:junit4]   2> 8895 T496 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8918 T496 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:57455/solr
[junit4:junit4]   2> 8919 T496 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57455/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@cc80cc8
[junit4:junit4]   2> 8921 T569 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57455
[junit4:junit4]   2> 8921 T496 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8922 T569 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:57455, initiating session
[junit4:junit4]   2> 8922 T498 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:45101
[junit4:junit4]   2> 8923 T498 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:45101
[junit4:junit4]   2> 8925 T500 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa0c786db0007 with negotiated timeout 5000 for client /127.0.0.1:45101
[junit4:junit4]   2> 8925 T569 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:57455, sessionid = 0x13aa0c786db0007, negotiated timeout = 5000
[junit4:junit4]   2> 8926 T570 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@cc80cc8 name:ZooKeeperConnection Watcher:127.0.0.1:57455/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8926 T496 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8927 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0007 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8929 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0007 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8930 T496 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56086_solr
[junit4:junit4]   2> 8931 T501 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa0c786db0007 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56086_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56086_solr
[junit4:junit4]   2> 8932 T496 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56086_solr
[junit4:junit4]   2> 8937 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8937 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8937 T532 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8937 T556 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8937 T516 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8938 T496 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8938 T544 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8938 T521 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8938 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8939 T556 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8940 T496 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8939 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8942 T496 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8943 T496 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8944 T496 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8944 T496 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8945 T496 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8946 T496 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8946 T496 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8947 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8954 T496 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8959 T496 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1351317170411/collection1
[junit4:junit4]   2> 8960 T496 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8961 T496 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8962 T496 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8963 T496 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1351317170411/collection1/'
[junit4:junit4]   2> 8964 T496 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1351317170411/collection1/lib/README' to classloader
[junit4:junit4]   2> 8965 T496 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1351317170411/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 9010 T496 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 9055 T496 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9057 T496 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9062 T496 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9180 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9181 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34672/solr",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34672_solr",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 9184 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9184 T556 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9184 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9185 T570 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9188 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9188 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9521 T496 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9526 T496 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9530 T496 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9539 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9555 T496 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9560 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9562 T496 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9563 T496 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1351317170411/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4/
[junit4:junit4]   2> 9564 T496 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7a197a19
[junit4:junit4]   2> 9565 T496 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 9567 T496 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@58855885; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 9567 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4 forceNew:false
[junit4:junit4]   2> 9568 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4
[junit4:junit4]   2> 9569 T496 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4/index/
[junit4:junit4]   2> 9570 T496 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 9571 T496 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4/index forceNew:false
[junit4:junit4]   2> 9577 T496 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7e247e24; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9577 T496 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 9578 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4/index
[junit4:junit4]   2> 9579 T496 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 9582 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 9583 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 9584 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 9584 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 9585 T496 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 9586 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 9587 T496 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 9588 T496 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9589 T496 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9590 T496 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 9591 T496 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 9592 T496 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9593 T496 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 9593 T496 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 9594 T496 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 9595 T496 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9596 T496 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9597 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9598 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9599 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9600 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9601 T496 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9602 T496 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9603 T496 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 9604 T496 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 9607 T496 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9608 T496 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 9610 T496 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 9612 T496 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4
[junit4:junit4]   2> 9614 T496 oass.SolrIndexSearcher.<init> Opening Searcher@5ac85ac8 main
[junit4:junit4]   2> 9615 T496 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9616 T496 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9617 T496 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 9624 T496 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 9676 T571 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5ac85ac8 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9690 T496 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10188 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10189 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56086/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:56086_solr",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 10192 T556 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10192 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10192 T570 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10192 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10192 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10193 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10692 T496 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 10694 T496 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:56086/solr shardId:shard2
[junit4:junit4]   2> 10697 T496 oasc.ZkController.register We are http://127.0.0.1:56086/solr/collection1/ and leader is http://127.0.0.1:45622/solr/collection1/
[junit4:junit4]   2> 10698 T496 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56086/solr
[junit4:junit4]   2> 10699 T496 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 10700 T496 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C28 name=collection1 org.apache.solr.core.SolrCore@30cd30cd url=http://127.0.0.1:56086/solr/collection1 node=127.0.0.1:56086_solr
[junit4:junit4]   2> 10701 T572 C28 P56086 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 10701 T496 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10702 T572 C28 P56086 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 10703 T572 C28 P56086 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10703 T496 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 10703 T496 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 10704 T572 C28 P56086 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:45622/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 10704 T572 C28 P56086 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:56086/solr START replicas=[http://127.0.0.1:45622/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 10704 T496 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10705 T572 C28 P56086 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 10706 T572 C28 P56086 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 10706 T572 C28 P56086 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 10706 T572 C28 P56086 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C29 name=collection1 org.apache.solr.core.SolrCore@192c192c url=http://127.0.0.1:45622/solr/collection1 node=127.0.0.1:45622_solr
[junit4:junit4]   2> 10759 T538 C29 P45622 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> 10760 T496 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C30 name=collection1 org.apache.solr.core.SolrCore@6eff6eff url=http://127.0.0.1:39643/solr/collection1 node=127.0.0.1:39643_solr
[junit4:junit4]   2> 10768 T514 C30 P39643 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16
[junit4:junit4]   2> 10774 T514 C30 P39643 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data
[junit4:junit4]   2> 10778 T514 C30 P39643 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@518b518b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10780 T514 C30 P39643 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 10883 T514 C30 P39643 /update {version=2&wt=javabin} {add=[1 (1416958755418931200)]} 0 117
[junit4:junit4]   2> 10974 T528 C27 P47896 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1
[junit4:junit4]   2> 10980 T528 C27 P47896 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@71047104; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10981 T528 C27 P47896 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2>  C26_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:34672/solr, state=recovering, node_name=127.0.0.1:34672_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 10996 T553 C26 P34672 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3
[junit4:junit4]   2> 11002 T553 C26 P34672 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@34413441; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 11003 T553 C26 P34672 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 11007 T553 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=12 
[junit4:junit4]   2> 11008 T528 C27 P47896 /update {version=2&wt=javabin} {add=[1 (1416958755628646400)]} 0 36
[junit4:junit4]   2> 11010 T511 C30 P39643 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11057 T511 C30 P39643 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@518b518b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@518b518b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0.si, segments_2, _0_Lucene41_0.pos, _0_Lucene41_0.doc, _0_Lucene41_0.tim, _0.fnm, _0.fdt, _0_Lucene41_0.tip, _0.fdx, _0_nrm.cfs, _0_nrm.cfe]
[junit4:junit4]   2> 11058 T511 C30 P39643 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0.si, segments_2, _0_Lucene41_0.pos, _0_Lucene41_0.doc, _0_Lucene41_0.tim, _0.fnm, _0.fdt, _0_Lucene41_0.tip, _0.fdx, _0_nrm.cfs, _0_nrm.cfe]
[junit4:junit4]   2> 11060 T511 C30 P39643 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data
[junit4:junit4]   2> 11068 T511 C30 P39643 oass.SolrIndexSearcher.<init> Opening Searcher@448e448e main
[junit4:junit4]   2> 11069 T511 C30 P39643 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11071 T519 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@448e448e main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4:junit4]   2> 11072 T519 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/control/data/index
[junit4:junit4]   2> 11073 T511 C30 P39643 /update {version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 63
[junit4:junit4]   2> 11075 T527 C27 P47896 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11107 T527 C27 P47896 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@71047104; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@71047104; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0.si, segments_2, _0_Lucene41_0.pos, _0_Lucene41_0.doc, _0_Lucene41_0.tim, _0.fnm, _0.fdt, _0_Lucene41_0.tip, _0.fdx, _0_nrm.cfs, _0_nrm.cfe]
[junit4:junit4]   2> 11108 T527 C27 P47896 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0.si, segments_2, _0_Lucene41_0.pos, _0_Lucene41_0.doc, _0_Lucene41_0.tim, _0.fnm, _0.fdt, _0_Lucene41_0.tip, _0.fdx, _0_nrm.cfs, _0_nrm.cfe]
[junit4:junit4]   2> 11110 T527 C27 P47896 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1
[junit4:junit4]   2> 11129 T527 C27 P47896 oass.SolrIndexSearcher.<init> Opening Searcher@43c343c3 main
[junit4:junit4]   2> 11130 T527 C27 P47896 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11133 T533 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@43c343c3 main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4:junit4]   2> 11133 T533 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1/index
[junit4:junit4]   2> 11134 T527 C27 P47896 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:34672/solr/collection1/, StdNode: http://127.0.0.1:45622/solr/collection1/, StdNode: http://127.0.0.1:56086/solr/collection1/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4]   2> 11136 T542 C29 P45622 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11136 T551 C26 P34672 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11138 T542 C29 P45622 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2
[junit4:junit4]   2> 11139 T568 C28 P56086 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11140 T568 C28 P56086 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4
[junit4:junit4]   2> 11144 T542 C29 P45622 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5f135f13; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 11145 T542 C29 P45622 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 11146 T568 C28 P56086 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7e247e24; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 11147 T542 C29 P45622 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2
[junit4:junit4]   2> 11147 T568 C28 P56086 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 11150 T568 C28 P56086 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4
[junit4:junit4]   2> 11150 T542 C29 P45622 oass.SolrIndexSearcher.<init> Opening Searcher@3a103a10 main
[junit4:junit4]   2> 11154 T545 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3a103a10 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 11155 T545 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2/index
[junit4:junit4]   2> 11156 T542 C29 P45622 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11170 T542 C29 P45622 /update {softCommit=false&commit_end_point=true&expungeDeletes=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 34
[junit4:junit4]   2> 11170 T568 C28 P56086 oass.SolrIndexSearcher.<init> Opening Searcher@4fb84fb8 main
[junit4:junit4]   2> 11171 T568 C28 P56086 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11174 T571 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4fb84fb8 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 11175 T571 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4/index
[junit4:junit4]   2> 11175 T568 C28 P56086 /update {softCommit=false&commit_end_point=true&expungeDeletes=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 36
[junit4:junit4]   2> 11187 T551 C26 P34672 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@34413441; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@34413441; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0.si, segments_2, _0_Lucene41_0.pos, _0_Lucene41_0.doc, _0_Lucene41_0.tim, _0.fnm, _0.fdt, _0_Lucene41_0.tip, _0.fdx, _0_nrm.cfs, _0_nrm.cfe]
[junit4:junit4]   2> 11188 T551 C26 P34672 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0.si, segments_2, _0_Lucene41_0.pos, _0_Lucene41_0.doc, _0_Lucene41_0.tim, _0.fnm, _0.fdt, _0_Lucene41_0.tip, _0.fdx, _0_nrm.cfs, _0_nrm.cfe]
[junit4:junit4]   2> 11190 T551 C26 P34672 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3
[junit4:junit4]   2> 11198 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11198 T551 C26 P34672 oass.SolrIndexSearcher.<init> Opening Searcher@98c098c main
[junit4:junit4]   2> 11199 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56086/solr",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:56086_solr",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 11200 T551 C26 P34672 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11202 T557 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@98c098c main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4:junit4]   2> 11203 T557 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index
[junit4:junit4]   2> 11203 T551 C26 P34672 /update {softCommit=false&commit_end_point=true&expungeDeletes=false&commit=true&version=2&waitSearcher=true&wt=javabin} {commit=} 0 67
[junit4:junit4]   2> 11204 T527 C27 P47896 /update {version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 129
[junit4:junit4]   2> 11205 T496 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):1800
[junit4:junit4]   2> 11207 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11207 T556 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11208 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11208 T496 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11208 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11208 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11208 T570 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11746 T558 C26 P34672 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 11747 T558 C26 P34672 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 11747 T558 C26 P34672 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:47896/solr/collection1/. core=collection1
[junit4:junit4]   2> 11748 T558 C26 P34672 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 11756 T528 C27 P47896 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11759 T528 C27 P47896 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1
[junit4:junit4]   2> 11763 T528 C27 P47896 oass.SolrIndexSearcher.<init> Opening Searcher@4ddc4ddc realtime
[junit4:junit4]   2> 11765 T528 C27 P47896 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11766 T528 C27 P47896 /update {softCommit=false&commit_end_point=true&commit=true&version=2&waitSearcher=true&wt=javabin&openSearcher=false} {commit=} 0 10
[junit4:junit4]   2> 11768 T558 C26 P34672 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 11769 T558 C26 P34672 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 11780 T558 C26 P34672 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 11782 T529 C27 P47896 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 11783 T529 C27 P47896 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 11785 T558 C26 P34672 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 11785 T558 C26 P34672 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4:junit4]   2> 11785 T558 C26 P34672 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 11851 T528 C27 P47896 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1
[junit4:junit4]   2> 11853 T528 C27 P47896 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty1/index
[junit4:junit4]   2> 11854 T528 C27 P47896 REQ /replication {generation=2&version=2&command=filelist&qt=/replication&wt=javabin} status=0 QTime=67 
[junit4:junit4]   2> 11861 T558 C26 P34672 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 11
[junit4:junit4]   2> 11862 T558 C26 P34672 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index.20121027095253538 lockFactory=org.apache.lucene.store.NativeFSLockFactory@9680968; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 11862 T558 C26 P34672 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index.20121027095253538 forceNew:false
[junit4:junit4]   2> 11863 T558 C26 P34672 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3
[junit4:junit4]   2> 11865 T558 C26 P34672 oash.SnapPuller.fetchLatestIndex Starting download to NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index.20121027095253538 lockFactory=org.apache.lucene.store.SimpleFSLockFactory@4f2b4f2b; maxCacheMB=48.0 maxMergeSizeMB=4.0) fullCopy=true
[junit4:junit4]   2> 11869 T527 C27 P47896 REQ /replication {qt=/replication&checksum=true&generation=2&command=filecontent&file=_0_Lucene41_0.pos&wt=filestream} status=0 QTime=1 
[junit4:junit4]   2> 11873 T530 C27 P47896 REQ /replication {qt=/replication&checksum=true&generation=2&command=filecontent&file=_0.fnm&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 11876 T529 C27 P47896 REQ /replication {qt=/replication&checksum=true&generation=2&command=filecontent&file=_0_Lucene41_0.tim&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 11881 T528 C27 P47896 REQ /replication {qt=/replication&checksum=true&generation=2&command=filecontent&file=_0_Lucene41_0.doc&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 11894 T527 C27 P47896 REQ /replication {qt=/replication&checksum=true&generation=2&command=filecontent&file=_0_Lucene41_0.tip&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 11897 T530 C27 P47896 REQ /replication {qt=/replication&checksum=true&generation=2&command=filecontent&file=_0_nrm.cfe&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 11900 T529 C27 P47896 REQ /replication {qt=/replication&checksum=true&generation=2&command=filecontent&file=segments_2&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 11913 T528 C27 P47896 REQ /replication {qt=/replication&checksum=true&generation=2&command=filecontent&file=_0.si&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 11916 T527 C27 P47896 REQ /replication {qt=/replication&checksum=true&generation=2&command=filecontent&file=_0.fdt&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 11919 T530 C27 P47896 REQ /replication {qt=/replication&checksum=true&generation=2&command=filecontent&file=_0_nrm.cfs&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 11936 T529 C27 P47896 REQ /replication {qt=/replication&checksum=true&generation=2&command=filecontent&file=_0.fdx&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 11937 T558 C26 P34672 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index
[junit4:junit4]   2> 11938 T558 C26 P34672 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 11939 T558 C26 P34672 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20121027095253538
[junit4:junit4]   2> 11940 T558 C26 P34672 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3
[junit4:junit4]   2> 11942 T558 C26 P34672 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 11942 T558 C26 P34672 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 11942 T558 C26 P34672 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 11944 T558 C26 P34672 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index
[junit4:junit4]   2> 11945 T558 C26 P34672 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3
[junit4:junit4]   2> 11946 T558 C26 P34672 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index/ new=./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index.20121027095253538
[junit4:junit4]   2> 11950 T558 C26 P34672 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index.20121027095253538 lockFactory=org.apache.lucene.store.SimpleFSLockFactory@4f2b4f2b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0.si, segments_2, _0_Lucene41_0.pos, _0.fnm, _0_Lucene41_0.tim, _0_Lucene41_0.doc, _0.fdt, _0_Lucene41_0.tip, _0_nrm.cfs, _0.fdx, _0_nrm.cfe]
[junit4:junit4]   2> 11951 T558 C26 P34672 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0.si, segments_2, _0_Lucene41_0.pos, _0.fnm, _0_Lucene41_0.tim, _0_Lucene41_0.doc, _0.fdt, _0_Lucene41_0.tip, _0_nrm.cfs, _0.fdx, _0_nrm.cfe]
[junit4:junit4]   2> 11952 T558 C26 P34672 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 11953 T558 C26 P34672 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3
[junit4:junit4]   2> 11954 T558 C26 P34672 oass.SolrIndexSearcher.<init> Opening Searcher@65246524 main
[junit4:junit4]   2> 11957 T557 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@65246524 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4:junit4]   2> 11958 T557 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index
[junit4:junit4]   2> 11958 T558 C26 P34672 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11959 T558 C26 P34672 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3
[junit4:junit4]   2> 11960 T558 C26 P34672 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11960 T558 C26 P34672 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index.20121027095253538
[junit4:junit4]   2> 11960 T558 C26 P34672 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index
[junit4:junit4]   2> 11961 T558 C26 P34672 oasc.CachingDirectoryFactory.close Closing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index
[junit4:junit4]   2> 11961 T558 C26 P34672 oash.SnapPuller$2.preClose removing old index files NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@34413441; maxCacheMB=48.0 maxMergeSizeMB=4.0)
[junit4:junit4]   2> 11965 T558 C26 P34672 oash.SnapPuller$2.postClose removing old index directory NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@34413441; maxCacheMB=48.0 maxMergeSizeMB=4.0)
[junit4:junit4]   2> 11982 T558 C26 P34672 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 11982 T558 C26 P34672 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 11983 T558 C26 P34672 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11984 T558 C26 P34672 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 12211 T496 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12213 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12214 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34672/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34672_solr",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 12217 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12217 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12217 T570 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12217 T556 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12217 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12217 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 13212 T496 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C28_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:56086/solr, state=recovering, node_name=127.0.0.1:56086_solr, roles=null, collection=collection1, shard=shard2, core=collection1}
[junit4:junit4]   2> 13761 T572 C28 P56086 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 13762 T572 C28 P56086 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 13762 T572 C28 P56086 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:45622/solr/collection1/. core=collection1
[junit4:junit4]   2> 13763 T572 C28 P56086 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 13772 T542 C29 P45622 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13774 T542 C29 P45622 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty2
[junit4:junit4]   2> 13775 T542 C29 P45622 oass.SolrIndexSearcher.<init> Opening Searcher@781a781a realtime
[junit4:junit4]   2> 13777 T542 C29 P45622 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13778 T542 C29 P45622 /update {softCommit=false&commit_end_point=true&commit=true&version=2&waitSearcher=true&wt=javabin&openSearcher=false} {commit=} 0 6
[junit4:junit4]   2> 13780 T572 C28 P56086 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 13781 T572 C28 P56086 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 13784 T537 C29 P45622 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 13785 T537 C29 P45622 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13787 T572 C28 P56086 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13827 T572 C28 P56086 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7e247e24; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7e247e24; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 13828 T572 C28 P56086 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 13829 T572 C28 P56086 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4
[junit4:junit4]   2> 13831 T572 C28 P56086 oass.SolrIndexSearcher.<init> Opening Searcher@32f132f1 main
[junit4:junit4]   2> 13832 T572 C28 P56086 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13834 T571 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@32f132f1 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 13835 T571 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1351317161680/jetty4/index
[junit4:junit4]   2> 13835 T572 C28 P56086 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 13836 T572 C28 P56086 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 13836 T572 C28 P56086 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 13838 T572 C28 P56086 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 14215 T496 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14223 T517 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14225 T517 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56086/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:56086_solr",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 14239 T516 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 14239 T556 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 14239 T521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 14239 T532 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 14240 T570 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 14240 T544 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 15216 T496 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15222 T514 C30 P39643 REQ /select {q=*:*&start=0&isShard=true&distrib=false&fl=id,score&NOW=1351317176897&version=2&rows=10&fsv=true&wt=javabin&shard.url=127.0.0.1:39643/solr/collection1/} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 15323 T513 C30 P39643 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1351317176897&ids=1&version=2&wt=javabin&shard.url=127.0.0.1:39643/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2> 15324 T512 C30 P39643 REQ /select {version=2&wt=javabin&q=*:*} hits=1 status=0 QTime=104 
[junit4:junit4]   2> 15326 T496 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:57455/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@21962196
[junit4:junit4]   2> 15327 T496 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 15328 T579 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:57455
[junit4:junit4]   2> 15329 T579 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:57455, initiating session
[junit4:junit4]   2> 15329 T498 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:45115
[junit4:junit4]   2> 15330 T498 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:45115
[junit4:junit4]   2> 15331 T500 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa0c786db0008 with negotiated timeout 10000 for client /127.0.0.1:45115
[junit4:junit4]   2> 15331 T579 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:57455, sessionid = 0x13aa0c786db0008, negotiated timeout = 10000
[junit4:junit4]   2> 15332 T580 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@21962196 name:ZooKeeperConnection Watcher:127.0.0.1:57455/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 15333 T496 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 15334 T496 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 15335 T501 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aa0c786db0008
[junit4:junit4]   2> 15336 T580 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 15336 T496 oaz.ZooKeeper.close Session: 0x13aa0c786db0008 closed
[junit4:junit4]   2> 15336 T498 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:45115 which had sessionid 0x13aa0c786db0008
[junit4:junit4]   2> 15338 T530 C27 P47896 REQ /select {version=2&q=*:*&wt=javabin&distrib=false} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 15340 T542 C29 P45622 REQ /select {version=2&q=*:*&wt=javabin&distrib=false} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 15344 T528 C27 P47896 REQ /select {q=*:*&start=0&isShard=true&distrib=false&fl=id,score&NOW=1351317177019&version=2&rows=10&fsv=true&wt=javabin&shard.url=127.0.0.1:47896/solr/collection1/|127.0.0.1:34672/solr/collection1/} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 15344 T538 C29 P45622 REQ /select {q=*:*&start=0&isShard=true&distrib=false&fl=id,score&NOW=1351317177019&version=2&rows=10&fsv=true&wt=javabin&shard.url=127.0.0.1:45622/solr/collection1/|127.0.0.1:56086/solr/collection1/} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 15346 T527 C27 P47896 REQ /select {q=*:*&isShard=true&distrib=false&NOW=1351317177019&ids=1&version=2&wt=javabin&shard.url=127.0.0.1:47896/solr/collection1/|127.0.0.1:34672/solr/collection1/} status=0 QTime=0 
[junit4:junit4]   2>  C26_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:34672/solr, state=active, node_name=127.0.0.1:34672_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 15360 T553 C26 P34672 REQ /select {version=2&wt=javabin&q=*:*} hits=1 status=0 QTime=17 
[junit4:junit4]   2> 15365 T514 C30 P39643 /update {version=2&wt=javabin} {add=[2 (1416958760230846464)]} 0 3
[junit4:junit4]   2>  C28_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:56086/solr, state=active, node_name=127.0.0.1:56086_solr, roles=null, collection=collection1, shard=shard2, core=collection1}
[junit4:junit4]   2> 15374 T566 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 15375 T537 C29 P45622 /update {version=2&wt=javabin} {add=[2 (1416958760236089344)]} 0 8
[junit4:junit4]   2> 15378 T511 C30 P39643 /update {version=2&wt=javabin} {add=[3 (1416958760247623680)]} 0 1
[junit4:junit4]   2> 15386 T567 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15387 T542 C29 P45622 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 15388 T530 C27 P47896 /update {version=2&wt=javabin} {add=[3]} 0 7
[junit4:junit4]   2> 15391 T513 C30 P39643 /update {version=2&wt=javabin} {add=[4 (1416958760261255168)]} 0 1
[junit4:junit4]   2> 15403 T551 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 15404 T529 C27 P47896 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=8 
[junit4:junit4]   2> 15405 T538 C29 P45622 /update {version=2&wt=javabin} {add=[4]} 0 11
[junit4:junit4]   2> 15408 T512 C30 P39643 /update {version=2&wt=javabin} {add=[5 (1416958760278032384)]} 0 1
[junit4:junit4]   2> 15415 T568 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15416 T542 C29 P45622 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 15417 T528 C27 P47896 /update {version=2&wt=javabin} {add=[5]} 0 7
[junit4:junit4]   2> 15420 T514 C30 P39643 /update {version=2&wt=javabin} {add=[6 (1416958760290615296)]} 0 1
[junit4:junit4]   2> 15428 T565 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15429 T540 C29 P45622 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 15430 T527 C27 P47896 /update {version=2&wt=javabin} {add=[6]} 0 8
[junit4:junit4]   2> 15434 T511 C30 P39643 /update {version=2&wt=javabin} {add=[7 (1416958760305295360)]} 0 1
[junit4:junit4]   2> 15441 T566 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15442 T537 C29 P45622 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 15443 T530 C27 P47896 /update {version=2&wt=javabin} {add=[7]} 0 6
[junit4:junit4]   2> 15447 T513 C30 P39643 /update {version=2&wt=javabin} {add=[8 (1416958760318926848)]} 0 2
[junit4:junit4]   2> 15456 T554 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15457 T529 C27 P47896 /update {version=2&wt=javabin} {add=[8 (1416958760326266880)]} 0 5
[junit4:junit4]   2> 15460 T512 C30 P39643 /update {version=2&wt=javabin} {add=[9 (1416958760333606912)]} 0 1
[junit4:junit4]   2> 15467 T567 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15468 T538 C29 P45622 /update {version=2&wt=javabin} {add=[9 (1416958760336752640)]} 0 5
[junit4:junit4]   2> 15471 T514 C30 P39643 /update {version=2&wt=javabin} {add=[10 (1416958760344092672)]} 0 1
[junit4:junit4]   2> 15477 T553 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15478 T528 C27 P47896 /update {version=2&wt=javabin} {add=[10 (1416958760348286976)]} 0 4
[junit4:junit4]   2> 15497 T511 C30 P39643 /update {version=2&wt=javabin} {add=[11 (1416958760354578432)]} 0 17
[junit4:junit4]   2> 15505 T552 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 15506 T527 C27 P47896 /update {version=2&wt=javabin} {add=[11 (1416958760375549952)]} 0 6
[junit4:junit4]   2> 15510 T512 C30 P39643 /update {version=2&wt=javabin} {add=[12 (1416958760383938560)]} 0 2
[junit4:junit4]   2> 15517 T551 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15519 T530 C27 P47896 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 15519 T542 C29 P45622 /update {version=2&wt=javabin} {add=[12]} 0 7
[junit4:junit4]   2> 15523 T514 C30 P39643 /update {version=2&wt=javabin} {add=[13 (1416958760398618624)]} 0 1
[junit4:junit4]   2> 15529 T554 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15530 T529 C27 P47896 /update {version=2&wt=javabin} {add=[13 (1416958760401764352)]} 0 5
[junit4:junit4]   2> 15534 T513 C30 P39643 /update {version=2&wt=javabin} {add=[14 (1416958760410152960)]} 0 1
[junit4:junit4]   2> 15542 T553 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15543 T528 C27 P47896 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 15544 T540 C29 P45622 /update {version=2&wt=javabin} {add=[14]} 0 7
[junit4:junit4]   2> 15548 T511 C30 P39643 /update {version=2&wt=javabin} {add=[15 (1416958760423784448)]} 0 2
[junit4:junit4]   2> 15555 T552 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15556 T527 C27 P47896 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 15558 T537 C29 P45622 /update {version=2&wt=javabin} {add=[15]} 0 8
[junit4:junit4]   2> 15562 T512 C30 P39643 /update {version=2&wt=javabin} {add=[16 (1416958760440561664)]} 0 1
[junit4:junit4]   2> 15569 T551 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 15570 T530 C27 P47896 /update {version=2&wt=javabin} {add=[16 (1416958760443707392)]} 0 5
[junit4:junit4]   2> 15574 T514 C30 P39643 /update {version=2&wt=javabin} {add=[17 (1416958760452096000)]} 0 2
[junit4:junit4]   2> 15595 T568 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 15596 T538 C29 P45622 /update {version=2&wt=javabin} {add=[17 (1416958760456290304)]} 0 20
[junit4:junit4]   2> 15599 T513 C30 P39643 /update {version=2&wt=javabin} {add=[100 (1416958760478310400)]} 0 1
[junit4:junit4]   2> 15613 T554 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 15614 T528 C27 P47896 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=11 
[junit4:junit4]   2> 15615 T542 C29 P45622 /update {version=2&wt=javabin} {add=[100]} 0 14
[junit4:junit4]   2> 15619 T511 C30 P39643 /update {version=2&wt=javabin} {add=[101 (1416958760499281920)]} 0 2
[junit4:junit4]   2> 15629 T553 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 15630 T527 C27 P47896 /update {version=2&wt=javabin} {add=[101 (1416958760505573376)]} 0 6
[junit4:junit4]   2> 15633 T512 C30 P39643 /update {version=2&wt=javabin} {add=[102 (1416958760513961984)]} 0 1
[junit4:junit4]   2> 15640 T552 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15640 T529 C27 P47896 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 15641 T540 C29 P45622 /update {version=2&wt=javabin} {add=[102]} 0 6
[junit4:junit4]   2> 15648 T514 C30 P39643 /update {version=2&wt=javabin} {add=[103 (1416958760526544896)]} 0 5
[junit4:junit4]   2> 15654 T566 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15656 T537 C29 P45622 /update {version=2&wt=javabin} {add=[103 (1416958760533884928)]} 0 5
[junit4:junit4]   2> 15661 T513 C30 P39643 /update {version=2&wt=javabin} {add=[104 (1416958760542273536)]} 0 2
[junit4:junit4]   2> 15678 T565 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=10 
[junit4:junit4]   2> 15679 T538 C29 P45622 /update {version=2&wt=javabin} {add=[104 (1416958760546467840)]} 0 16
[junit4:junit4]   2> 15682 T511 C30 P39643 /update {version=2&wt=javabin} {add=[105 (1416958760565342208)]} 0 1
[junit4:junit4]   2> 15696 T568 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 15697 T542 C29 P45622 /update {version=2&wt=javabin} {add=[105 (1416958760569536512)]} 0 12
[junit4:junit4]   2> 15700 T512 C30 P39643 /update {version=2&wt=javabin} {add=[106 (1416958760584216576)]} 0 1
[junit4:junit4]   2> 15708 T566 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 15708 T540 C29 P45622 /update {version=2&wt=javabin} {add=[106 (1416958760587362304)]} 0 6
[junit4:junit4]   2> 15712 T514 C30 P39643 /update {version=2&wt=javabin} {add=[107 (1416958760596799488)]} 0 1
[junit4:junit4]   2> 15717 T551 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15718 T530 C27 P47896 /update {version=2&wt=javabin} {add=[107 (1416958760599945216)]} 0 4
[junit4:junit4]   2> 15722 T513 C30 P39643 /update {version=2&wt=javabin} {add=[108 (1416958760607285248)]} 0 1
[junit4:junit4]   2> 15727 T554 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15728 T528 C27 P47896 /update {version=2&wt=javabin} {add=[108 (1416958760610430976)]} 0 4
[junit4:junit4]   2> 15731 T512 C30 P39643 /update {version=2&wt=javabin} {add=[109 (1416958760616722432)]} 0 1
[junit4:junit4]   2> 15738 T553 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15739 T527 C27 P47896 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 15740 T537 C29 P45622 /update {version=2&wt=javabin} {add=[109]} 0 7
[junit4:junit4]   2> 15743 T514 C30 P39643 /update {version=2&wt=javabin} {add=[110 (1416958760629305344)]} 0 1
[junit4:junit4]   2> 15747 T552 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 15748 T530 C27 P47896 /update {version=2&wt=javabin} {add=[110 (1416958760632451072)]} 0 3
[junit4:junit4]   2> 15751 T513 C30 P39643 /update {version=2&wt=javabin} {add=[111 (1416958760637693952)]} 0 1
[junit4:junit4]   2> 15757 T551 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15758 T528 C27 P47896 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 15759 T538 C29 P45622 /update {version=2&wt=javabin} {add=[111]} 0 6
[junit4:junit4]   2> 15763 T511 C30 P39643 /update {version=2&wt=javabin} {add=[112 (1416958760649228288)]} 0 2
[junit4:junit4]   2> 15770 T567 C28 P56086 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:45622/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15771 T542 C29 P45622 /update {version=2&wt=javabin} {add=[112 (1416958760654471168)]} 0 6
[junit4:junit4]   2> 15788 T512 C30 P39643 /update {version=2&wt=javabin} {add=[113 (1416958760673345536)]} 0 4
[junit4:junit4]   2> 15795 T553 C26 P34672 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:47896/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 15796 T527 C27 P47896 REQ /update {update.distrib=TOLEADER&distrib.fro

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

ethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:611)
[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:45)
[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:48)
[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:45)
[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:738)
[junit4:junit4]   2> 22482 T496 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 22547 T495 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4:junit4]   2> 24316 T570 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard2/election/88559921508581383-127.0.0.1:56086_solr_collection1-n_0000000001
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> 	at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:734)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:144)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:141)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:63)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:141)
[junit4:junit4]   2> 	at org.apache.solr.cloud.ElectionContext.cancelElection(ElectionContext.java:63)
[junit4:junit4]   2> 	at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:243)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:155)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:99)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:128)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> 
[junit4:junit4]   2> 24317 T570 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 24317 T570 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 24318 T570 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 24318 T570 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 24319 T570 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 24319 T570 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@cc80cc8 name:ZooKeeperConnection Watcher:127.0.0.1:57455/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 24321 T570 oascc.ConnectionManager.process Client->ZooKeeper status change trigger but we are already closed
[junit4:junit4]   2> 24322 T570 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> NOTE: test params are: codec=Compressing(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_UNCOMPRESSION, chunkSize=449, storedFieldsIndex=DISK_DOC)), sim=DefaultSimilarity, locale=en_SG, timezone=Indian/Mauritius
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic x86/IBM Corporation 1.6.0 (32-bit)/cpus=8,threads=1,free=37227504,total=51306496
[junit4:junit4]   2> NOTE: All tests run in this JVM: [ScriptEngineTest, TestFastWriter, ZkSolrClientTest, DocumentAnalysisRequestHandlerTest, DirectUpdateHandlerTest, TestReversedWildcardFilterFactory, SuggesterTest, XmlUpdateRequestHandlerTest, TestHashPartitioner, ConvertedLegacyTest, TimeZoneUtilsTest, PrimitiveFieldTypeTest, TestDistributedGrouping, AnalysisAfterCoreReloadTest, SolrIndexConfigTest, TestCodecSupport, LeaderElectionTest, ShowFileRequestHandlerTest, TestSolrCoreProperties, PrimUtilsTest, TestCSVResponseWriter, XsltUpdateRequestHandlerTest, URLClassifyProcessorTest, TestOmitPositions, SpellingQueryConverterTest, SolrCoreTest, SimplePostToolTest, TestWriterPerf, UUIDFieldTest, TestFoldingMultitermQuery, BasicDistributedZk2Test]
[junit4:junit4] Completed on J1 in 24.58s, 1 test, 1 failure <<< FAILURES!

[...truncated 608 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:348: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:178: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/module-build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1159: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:827: There were test failures: 232 suites, 961 tests, 1 failure, 7 ignored (2 assumptions)

Total time: 31 minutes 50 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message