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 (64bit/ibm-j9-jdk6) - Build # 3023 - Failure!
Date Sun, 02 Dec 2012 18:19:14 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/3023/
Java: 64bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}

1 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.BasicDistributedZkTest

Error Message:
ERROR: SolrIndexSearcher opens=1597 closes=1596

Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=1597 closes=1596
	at __randomizedtesting.SeedInfo.seed([BF445EDC8BB63CD]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:243)
	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:97)
	at sun.reflect.GeneratedMethodAccessor25.invoke(Unknown Source)
	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$5.evaluate(RandomizedRunner.java:700)
	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 8474 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 3 T1348 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZkTest-1354471697181
[junit4:junit4]   2> 5 T1348 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 6 T1349 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 7 T1349 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 8 T1349 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 8 T1349 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 9 T1349 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 10 T1349 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 106 T1348 oasc.ZkTestServer.run start zk server on port:59899
[junit4:junit4]   2> 107 T1348 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59899 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@13bf13bf
[junit4:junit4]   2> 108 T1348 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 109 T1354 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59899. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 110 T1354 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59899, initiating session
[junit4:junit4]   2> 110 T1350 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:55918
[junit4:junit4]   2> 111 T1350 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:55918
[junit4:junit4]   2> 111 T1352 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 125 T1352 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b5ccdeb230000 with negotiated timeout 10000 for client /127.0.0.1:55918
[junit4:junit4]   2> 125 T1354 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59899, sessionid = 0x13b5ccdeb230000, negotiated timeout = 10000
[junit4:junit4]   2> 126 T1355 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13bf13bf name:ZooKeeperConnection Watcher:127.0.0.1:59899 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 126 T1348 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 127 T1348 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 134 T1353 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13b5ccdeb230000
[junit4:junit4]   2> 134 T1350 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:55918 which had sessionid 0x13b5ccdeb230000
[junit4:junit4]   2> 135 T1355 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 135 T1348 oaz.ZooKeeper.close Session: 0x13b5ccdeb230000 closed
[junit4:junit4]   2> 136 T1348 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59899/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7ee07ee0
[junit4:junit4]   2> 138 T1348 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 140 T1356 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59899. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 141 T1356 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59899, initiating session
[junit4:junit4]   2> 141 T1350 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:55919
[junit4:junit4]   2> 143 T1350 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:55919
[junit4:junit4]   2> 145 T1352 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b5ccdeb230001 with negotiated timeout 10000 for client /127.0.0.1:55919
[junit4:junit4]   2> 145 T1356 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59899, sessionid = 0x13b5ccdeb230001, negotiated timeout = 10000
[junit4:junit4]   2> 146 T1357 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7ee07ee0 name:ZooKeeperConnection Watcher:127.0.0.1:59899/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 146 T1348 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 147 T1348 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 151 T1348 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 155 T1348 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 159 T1348 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 163 T1348 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> 164 T1348 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 171 T1348 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> 173 T1348 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 177 T1348 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> 178 T1348 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 183 T1348 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> 184 T1348 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 188 T1348 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> 189 T1348 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 195 T1348 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> 196 T1348 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 201 T1348 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> 203 T1348 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 208 T1348 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> 210 T1348 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 219 T1348 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> 221 T1348 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 227 T1348 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> 228 T1348 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 233 T1353 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13b5ccdeb230001
[junit4:junit4]   2> 235 T1350 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:55919 which had sessionid 0x13b5ccdeb230001
[junit4:junit4]   2> 235 T1357 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 235 T1348 oaz.ZooKeeper.close Session: 0x13b5ccdeb230001 closed
[junit4:junit4]   2> 357 T1348 oejs.Server.doStart jetty-8.1.7.v20120910
[junit4:junit4]   2> 360 T1348 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:38511
[junit4:junit4]   2> 361 T1348 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 361 T1348 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1354471697414
[junit4:junit4]   2> 362 T1348 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1354471697414/'
[junit4:junit4]   2> 373 T1348 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 374 T1348 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 374 T1348 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1354471697414
[junit4:junit4]   2> 375 T1348 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1354471697414/solr.xml
[junit4:junit4]   2> 375 T1348 oasc.CoreContainer.<init> New CoreContainer 236719644
[junit4:junit4]   2> 376 T1348 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1354471697414/'
[junit4:junit4]   2> 376 T1348 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1354471697414/'
[junit4:junit4]   2> 390 T1348 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 399 T1348 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:59899/solr
[junit4:junit4]   2> 400 T1348 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59899/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@34bd34bd
[junit4:junit4]   2> 401 T1348 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 402 T1367 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59899. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 403 T1367 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59899, initiating session
[junit4:junit4]   2> 403 T1350 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:55920
[junit4:junit4]   2> 404 T1350 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:55920
[junit4:junit4]   2> 405 T1352 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b5ccdeb230002 with negotiated timeout 8000 for client /127.0.0.1:55920
[junit4:junit4]   2> 405 T1367 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59899, sessionid = 0x13b5ccdeb230002, negotiated timeout = 8000
[junit4:junit4]   2> 406 T1368 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@34bd34bd name:ZooKeeperConnection Watcher:127.0.0.1:59899/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 407 T1348 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 409 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:create cxid:0x2 zxid:0x19 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 412 T1348 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 415 T1348 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38511_solr
[junit4:junit4]   2> 416 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:delete cxid:0x7 zxid:0x1b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:38511_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:38511_solr
[junit4:junit4]   2> 417 T1348 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:38511_solr
[junit4:junit4]   2> 421 T1348 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 422 T1348 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 422 T1348 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 423 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 423 T1348 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 424 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 425 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 425 T1348 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 426 T1348 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 426 T1348 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 432 T1348 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 439 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:delete cxid:0x15 zxid:0x20 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 440 T1348 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 445 T1348 oasc.Overseer.start Overseer (id=88766657146847234-127.0.0.1:38511_solr-n_0000000000) starting
[junit4:junit4]   2> 446 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:create cxid:0x1a zxid:0x22 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 448 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 449 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 451 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 452 T1370 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4]   2> 453 T1348 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 457 T1348 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 461 T1369 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 465 T1371 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1354471697414/collection1
[junit4:junit4]   2> 466 T1371 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 467 T1371 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 468 T1371 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 470 T1371 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1354471697414/collection1/'
[junit4:junit4]   2> 471 T1371 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1354471697414/collection1/lib/README' to classloader
[junit4:junit4]   2> 472 T1371 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1354471697414/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 517 T1371 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 714 T15 oasc.CoreContainer.finalize SEVERE CoreContainer was not shutdown prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!!  instance=1526553341
[junit4:junit4]   2> 742 T1371 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 743 T1371 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 748 T1371 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1069 T1371 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1074 T1371 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1077 T1371 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1096 T1371 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1100 T1371 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1105 T1371 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1107 T1371 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1109 T1371 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1354471697414/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data/
[junit4:junit4]   2> 1109 T1371 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@434a434a
[junit4:junit4]   2> 1110 T1371 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 1114 T1371 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data lockFactory=org.apache.lucene.store.NativeFSLockFactory@6a286a28; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 1114 T1371 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data forceNew:false
[junit4:junit4]   2> 1115 T1371 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data
[junit4:junit4]   2> 1115 T1371 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data/index/
[junit4:junit4]   2> 1116 T1371 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1117 T1371 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data/index forceNew:false
[junit4:junit4]   2> 1123 T1371 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@65426542; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1123 T1371 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1124 T1371 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data/index
[junit4:junit4]   2> 1124 T1371 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1127 T1371 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1128 T1371 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1128 T1371 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1129 T1371 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1130 T1371 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1130 T1371 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1131 T1371 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1133 T1371 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1134 T1371 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1136 T1371 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1138 T1371 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1139 T1371 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1141 T1371 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1141 T1371 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1142 T1371 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1144 T1371 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1145 T1371 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1146 T1371 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1148 T1371 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1149 T1371 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1151 T1371 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1152 T1371 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1154 T1371 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1155 T1371 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1157 T1371 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1158 T1371 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1159 T1371 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1162 T1371 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> 1164 T1371 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data
[junit4:junit4]   2> 1165 T1371 oass.SolrIndexSearcher.<init> Opening Searcher@41124112 main
[junit4:junit4]   2> 1166 T1371 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1166 T1371 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1167 T1371 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1174 T1371 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1220 T1372 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@41124112 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1377 T1371 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1378 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:create cxid:0x53 zxid:0x28 txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1471 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1473 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:38511/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:38511_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 1473 T1369 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 1474 T1369 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 1475 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:create cxid:0x5b zxid:0x2b txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1483 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1881 T1371 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 1882 T1371 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:38511/solr shardId:shard1
[junit4:junit4]   2> 1883 T1371 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 1898 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:delete cxid:0x75 zxid:0x35 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 1899 T1371 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1900 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:create cxid:0x76 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1903 T1371 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1904 T1371 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1904 T1371 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:38511/solr/collection1/
[junit4:junit4]   2> 1905 T1371 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1906 T1371 oasc.SyncStrategy.syncToMe http://127.0.0.1:38511/solr/collection1/ has no replicas
[junit4:junit4]   2> 1906 T1371 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:38511/solr/collection1/
[junit4:junit4]   2> 1907 T1371 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 1918 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230002 type:create cxid:0x80 zxid:0x3a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1992 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2007 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2021 T1371 oasc.ZkController.register We are http://127.0.0.1:38511/solr/collection1/ and leader is http://127.0.0.1:38511/solr/collection1/
[junit4:junit4]   2> 2022 T1371 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:38511/solr
[junit4:junit4]   2> 2023 T1371 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2023 T1371 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2026 T1371 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2028 T1348 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 2029 T1348 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2030 T1348 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2036 T1348 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 2042 T1348 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59899/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@55645564
[junit4:junit4]   2> 2043 T1348 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2044 T1373 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59899. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 2045 T1373 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59899, initiating session
[junit4:junit4]   2> 2045 T1350 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:55937
[junit4:junit4]   2> 2046 T1350 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:55937
[junit4:junit4]   2> 2047 T1352 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b5ccdeb230003 with negotiated timeout 10000 for client /127.0.0.1:55937
[junit4:junit4]   2> 2047 T1373 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59899, sessionid = 0x13b5ccdeb230003, negotiated timeout = 10000
[junit4:junit4]   2> 2048 T1374 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@55645564 name:ZooKeeperConnection Watcher:127.0.0.1:59899/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2048 T1348 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2050 T1348 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2054 T1348 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 2194 T1348 oejs.Server.doStart jetty-8.1.7.v20120910
[junit4:junit4]   2> 2195 T1348 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:34263
[junit4:junit4]   2> 2196 T1348 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2197 T1348 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1354471699231
[junit4:junit4]   2> 2198 T1348 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1354471699231/'
[junit4:junit4]   2> 2208 T1348 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2209 T1348 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2210 T1348 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1354471699231
[junit4:junit4]   2> 2210 T1348 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1354471699231/solr.xml
[junit4:junit4]   2> 2211 T1348 oasc.CoreContainer.<init> New CoreContainer 1573871055
[junit4:junit4]   2> 2212 T1348 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1354471699231/'
[junit4:junit4]   2> 2212 T1348 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1354471699231/'
[junit4:junit4]   2> 2226 T1348 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2236 T1348 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:59899/solr
[junit4:junit4]   2> 2237 T1348 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59899/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@38de38de
[junit4:junit4]   2> 2238 T1348 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2239 T1384 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59899. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 2240 T1384 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59899, initiating session
[junit4:junit4]   2> 2240 T1350 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:55938
[junit4:junit4]   2> 2240 T1350 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:55938
[junit4:junit4]   2> 2241 T1352 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b5ccdeb230004 with negotiated timeout 8000 for client /127.0.0.1:55938
[junit4:junit4]   2> 2242 T1384 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59899, sessionid = 0x13b5ccdeb230004, negotiated timeout = 8000
[junit4:junit4]   2> 2242 T1385 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@38de38de name:ZooKeeperConnection Watcher:127.0.0.1:59899/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2243 T1348 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2244 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230004 type:create cxid:0x1 zxid:0x46 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2245 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230004 type:create cxid:0x2 zxid:0x47 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2247 T1348 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34263_solr
[junit4:junit4]   2> 2248 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230004 type:delete cxid:0x4 zxid:0x48 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:34263_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:34263_solr
[junit4:junit4]   2> 2249 T1348 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34263_solr
[junit4:junit4]   2> 2253 T1374 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2254 T1368 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2254 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2254 T1348 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2255 T1348 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2255 T1348 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2256 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2257 T1348 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2257 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2258 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2258 T1348 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2259 T1348 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2260 T1348 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2268 T1348 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2277 T1386 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1354471699231/collection1
[junit4:junit4]   2> 2278 T1386 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2279 T1386 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2279 T1386 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2282 T1386 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1354471699231/collection1/'
[junit4:junit4]   2> 2283 T1386 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1354471699231/collection1/lib/README' to classloader
[junit4:junit4]   2> 2283 T1386 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1354471699231/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2315 T1386 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2357 T1386 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2359 T1386 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2363 T1386 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2656 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2658 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:38511/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:38511_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 2663 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2663 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2663 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2858 T1386 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2863 T1386 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2865 T1386 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2894 T1386 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2902 T1386 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2910 T1386 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2914 T1386 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2917 T1386 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1354471699231/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1/
[junit4:junit4]   2> 2918 T1386 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@434a434a
[junit4:junit4]   2> 2920 T1386 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 2922 T1386 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3e4c3e4c; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 2923 T1386 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1 forceNew:false
[junit4:junit4]   2> 2924 T1386 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1
[junit4:junit4]   2> 2925 T1386 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1/index/
[junit4:junit4]   2> 2927 T1386 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2928 T1386 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1/index forceNew:false
[junit4:junit4]   2> 2939 T1386 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2250225; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2940 T1386 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 2942 T1386 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1/index
[junit4:junit4]   2> 2943 T1386 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2948 T1386 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2949 T1386 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2950 T1386 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2952 T1386 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2953 T1386 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2954 T1386 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2955 T1386 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2959 T1386 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2963 T1386 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2965 T1386 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2967 T1386 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2968 T1386 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2969 T1386 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2970 T1386 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2970 T1386 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2972 T1386 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2974 T1386 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2976 T1386 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2977 T1386 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2979 T1386 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2981 T1386 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2983 T1386 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2984 T1386 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2986 T1386 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2988 T1386 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2989 T1386 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2991 T1386 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2994 T1386 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> 2996 T1386 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1
[junit4:junit4]   2> 2997 T1386 oass.SolrIndexSearcher.<init> Opening Searcher@5a045a04 main
[junit4:junit4]   2> 2998 T1386 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2999 T1386 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3000 T1386 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3006 T1386 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3069 T1387 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5a045a04 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3078 T1386 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3175 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3178 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34263/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34263_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> 3179 T1369 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 3179 T1369 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 3191 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3191 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3191 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3580 T1386 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 3581 T1386 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:34263/solr shardId:shard1
[junit4:junit4]   2> 3584 T1386 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3605 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230004 type:delete cxid:0x45 zxid:0x58 txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 3606 T1386 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3608 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230004 type:create cxid:0x46 zxid:0x59 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3614 T1386 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3615 T1386 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3616 T1386 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:34263/solr/collection1/
[junit4:junit4]   2> 3617 T1386 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3618 T1386 oasc.SyncStrategy.syncToMe http://127.0.0.1:34263/solr/collection1/ has no replicas
[junit4:junit4]   2> 3619 T1386 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:34263/solr/collection1/
[junit4:junit4]   2> 3620 T1386 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3634 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230004 type:create cxid:0x50 zxid:0x5d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3706 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3731 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3731 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3732 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3740 T1386 oasc.ZkController.register We are http://127.0.0.1:34263/solr/collection1/ and leader is http://127.0.0.1:34263/solr/collection1/
[junit4:junit4]   2> 3741 T1386 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34263/solr
[junit4:junit4]   2> 3742 T1386 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3743 T1386 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3746 T1386 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3747 T1348 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3748 T1348 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3749 T1348 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4023 T1348 oejs.Server.doStart jetty-8.1.7.v20120910
[junit4:junit4]   2> 4025 T1348 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:58793
[junit4:junit4]   2> 4027 T1348 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4028 T1348 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1354471700931
[junit4:junit4]   2> 4029 T1348 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1354471700931/'
[junit4:junit4]   2> 4040 T1348 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4041 T1348 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4041 T1348 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1354471700931
[junit4:junit4]   2> 4042 T1348 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1354471700931/solr.xml
[junit4:junit4]   2> 4042 T1348 oasc.CoreContainer.<init> New CoreContainer 1062682455
[junit4:junit4]   2> 4043 T1348 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1354471700931/'
[junit4:junit4]   2> 4044 T1348 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1354471700931/'
[junit4:junit4]   2> 4057 T1348 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4067 T1348 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:59899/solr
[junit4:junit4]   2> 4067 T1348 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59899/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@3b543b54
[junit4:junit4]   2> 4068 T1348 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4069 T1397 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59899. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 4070 T1397 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59899, initiating session
[junit4:junit4]   2> 4070 T1350 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:55939
[junit4:junit4]   2> 4071 T1350 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:55939
[junit4:junit4]   2> 4072 T1352 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b5ccdeb230005 with negotiated timeout 8000 for client /127.0.0.1:55939
[junit4:junit4]   2> 4072 T1397 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59899, sessionid = 0x13b5ccdeb230005, negotiated timeout = 8000
[junit4:junit4]   2> 4073 T1398 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3b543b54 name:ZooKeeperConnection Watcher:127.0.0.1:59899/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4074 T1348 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4075 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230005 type:create cxid:0x1 zxid:0x68 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4077 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230005 type:create cxid:0x2 zxid:0x69 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4079 T1348 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58793_solr
[junit4:junit4]   2> 4080 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230005 type:delete cxid:0x4 zxid:0x6a txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:58793_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:58793_solr
[junit4:junit4]   2> 4081 T1348 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58793_solr
[junit4:junit4]   2> 4085 T1368 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4085 T1374 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4085 T1385 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4086 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4086 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4086 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4086 T1348 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4089 T1348 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4090 T1348 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4091 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4091 T1348 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4092 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4092 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4093 T1348 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4094 T1348 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4094 T1348 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4103 T1348 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4111 T1399 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1354471700931/collection1
[junit4:junit4]   2> 4112 T1399 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4115 T1399 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4116 T1399 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4119 T1399 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1354471700931/collection1/'
[junit4:junit4]   2> 4120 T1399 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1354471700931/collection1/lib/README' to classloader
[junit4:junit4]   2> 4121 T1399 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1354471700931/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4173 T1399 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4211 T1399 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4214 T1399 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4219 T1399 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4247 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4249 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:34263/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:34263_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> 4255 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4256 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4256 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4256 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4709 T1399 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4714 T1399 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4717 T1399 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4737 T1399 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4742 T1399 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4747 T1399 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4750 T1399 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4752 T1399 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1354471700931/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2/
[junit4:junit4]   2> 4753 T1399 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@434a434a
[junit4:junit4]   2> 4754 T1399 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 4754 T1399 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@67f967f9; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 4755 T1399 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2 forceNew:false
[junit4:junit4]   2> 4756 T1399 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2
[junit4:junit4]   2> 4756 T1399 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2/index/
[junit4:junit4]   2> 4757 T1399 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4757 T1399 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2/index forceNew:false
[junit4:junit4]   2> 4763 T1399 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7fc87fc8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4764 T1399 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 4764 T1399 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2/index
[junit4:junit4]   2> 4765 T1399 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4767 T1399 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4768 T1399 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4768 T1399 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4769 T1399 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4770 T1399 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4771 T1399 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4773 T1399 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4776 T1399 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4779 T1399 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4781 T1399 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4783 T1399 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4783 T1399 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4785 T1399 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4785 T1399 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4786 T1399 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4788 T1399 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4789 T1399 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4791 T1399 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4792 T1399 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4794 T1399 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4796 T1399 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4797 T1399 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4799 T1399 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4800 T1399 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4802 T1399 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4803 T1399 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4805 T1399 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4807 T1399 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> 4809 T1399 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2
[junit4:junit4]   2> 4810 T1399 oass.SolrIndexSearcher.<init> Opening Searcher@84d084d main
[junit4:junit4]   2> 4811 T1399 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4812 T1399 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4812 T1399 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4819 T1399 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4874 T1400 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@84d084d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4888 T1399 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5270 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5273 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:58793/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:58793_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> 5274 T1369 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 5275 T1369 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 5286 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5286 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5287 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5286 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5390 T1399 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 5391 T1399 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:58793/solr shardId:shard2
[junit4:junit4]   2> 5394 T1399 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 5413 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230005 type:delete cxid:0x44 zxid:0x79 txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> 5415 T1399 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 5416 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230005 type:create cxid:0x45 zxid:0x7a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5421 T1399 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 5422 T1399 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 5423 T1399 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:58793/solr/collection1/
[junit4:junit4]   2> 5424 T1399 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 5425 T1399 oasc.SyncStrategy.syncToMe http://127.0.0.1:58793/solr/collection1/ has no replicas
[junit4:junit4]   2> 5426 T1399 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58793/solr/collection1/
[junit4:junit4]   2> 5427 T1399 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 5439 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230005 type:create cxid:0x4e zxid:0x7d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5802 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5826 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5826 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5826 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5826 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5845 T1399 oasc.ZkController.register We are http://127.0.0.1:58793/solr/collection1/ and leader is http://127.0.0.1:58793/solr/collection1/
[junit4:junit4]   2> 5846 T1399 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58793/solr
[junit4:junit4]   2> 5846 T1399 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 5847 T1399 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5849 T1399 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5851 T1348 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 5852 T1348 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5853 T1348 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6021 T1348 oejs.Server.doStart jetty-8.1.7.v20120910
[junit4:junit4]   2> 6023 T1348 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:59730
[junit4:junit4]   2> 6024 T1348 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6025 T1348 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1354471703036
[junit4:junit4]   2> 6026 T1348 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1354471703036/'
[junit4:junit4]   2> 6042 T1348 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6044 T1348 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6044 T1348 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1354471703036
[junit4:junit4]   2> 6045 T1348 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1354471703036/solr.xml
[junit4:junit4]   2> 6046 T1348 oasc.CoreContainer.<init> New CoreContainer 1068318637
[junit4:junit4]   2> 6046 T1348 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1354471703036/'
[junit4:junit4]   2> 6047 T1348 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1354471703036/'
[junit4:junit4]   2> 6208 T1348 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6217 T1348 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:59899/solr
[junit4:junit4]   2> 6218 T1348 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59899/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@61a061a0
[junit4:junit4]   2> 6219 T1348 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6220 T1410 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59899. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 6221 T1410 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59899, initiating session
[junit4:junit4]   2> 6221 T1350 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:55959
[junit4:junit4]   2> 6222 T1350 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:55959
[junit4:junit4]   2> 6223 T1352 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b5ccdeb230006 with negotiated timeout 8000 for client /127.0.0.1:55959
[junit4:junit4]   2> 6223 T1410 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59899, sessionid = 0x13b5ccdeb230006, negotiated timeout = 8000
[junit4:junit4]   2> 6224 T1411 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@61a061a0 name:ZooKeeperConnection Watcher:127.0.0.1:59899/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6224 T1348 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6225 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230006 type:create cxid:0x1 zxid:0x88 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6227 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230006 type:create cxid:0x2 zxid:0x89 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6228 T1348 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59730_solr
[junit4:junit4]   2> 6229 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230006 type:delete cxid:0x4 zxid:0x8a txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:59730_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:59730_solr
[junit4:junit4]   2> 6230 T1348 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59730_solr
[junit4:junit4]   2> 6235 T1398 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6235 T1385 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6235 T1374 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6235 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6236 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6236 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6236 T1348 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6235 T1368 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6238 T1348 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6239 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6239 T1348 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6240 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6241 T1348 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6241 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6242 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6242 T1348 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6243 T1348 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6243 T1348 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6252 T1348 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6260 T1412 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1354471703036/collection1
[junit4:junit4]   2> 6260 T1412 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6262 T1412 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6262 T1412 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6264 T1412 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1354471703036/collection1/'
[junit4:junit4]   2> 6266 T1412 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1354471703036/collection1/lib/README' to classloader
[junit4:junit4]   2> 6267 T1412 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1354471703036/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6296 T1412 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 6338 T1412 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6339 T1412 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6344 T1412 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6347 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6349 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:58793/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:58793_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> 6354 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6354 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6355 T1411 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6355 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6355 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6819 T1412 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6824 T1412 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6827 T1412 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6848 T1412 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6853 T1412 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6859 T1412 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6862 T1412 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6865 T1412 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1354471703036/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3/
[junit4:junit4]   2> 6866 T1412 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@434a434a
[junit4:junit4]   2> 6867 T1412 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 6868 T1412 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3df43df4; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 6868 T1412 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3 forceNew:false
[junit4:junit4]   2> 6869 T1412 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3
[junit4:junit4]   2> 6869 T1412 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3/index/
[junit4:junit4]   2> 6870 T1412 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6871 T1412 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3/index forceNew:false
[junit4:junit4]   2> 6878 T1412 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@61036103; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6879 T1412 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 6880 T1412 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3/index
[junit4:junit4]   2> 6881 T1412 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6885 T1412 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6886 T1412 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6887 T1412 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6887 T1412 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6888 T1412 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6889 T1412 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6890 T1412 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6893 T1412 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6896 T1412 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6899 T1412 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6901 T1412 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6902 T1412 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6904 T1412 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6904 T1412 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6905 T1412 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6907 T1412 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6908 T1412 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6910 T1412 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6912 T1412 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6914 T1412 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6915 T1412 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6917 T1412 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6919 T1412 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6921 T1412 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6922 T1412 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6924 T1412 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6925 T1412 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6928 T1412 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> 6930 T1412 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3
[junit4:junit4]   2> 6931 T1412 oass.SolrIndexSearcher.<init> Opening Searcher@3d563d56 main
[junit4:junit4]   2> 6932 T1412 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6933 T1412 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6934 T1412 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6941 T1412 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 7013 T1413 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3d563d56 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7028 T1412 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7372 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7375 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:59730/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:59730_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> 7376 T1369 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 7377 T1369 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 7389 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7389 T1411 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7390 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7390 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7390 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7530 T1412 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 7531 T1412 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:59730/solr shardId:shard1
[junit4:junit4]   2> 7540 T1412 oasc.ZkController.register We are http://127.0.0.1:59730/solr/collection1/ and leader is http://127.0.0.1:34263/solr/collection1/
[junit4:junit4]   2> 7541 T1412 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:59730/solr
[junit4:junit4]   2> 7542 T1412 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 7543 T1412 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C52 name=collection1 org.apache.solr.core.SolrCore@5b205b2 url=http://127.0.0.1:59730/solr/collection1 node=127.0.0.1:59730_solr
[junit4:junit4]   2> 7547 T1414 C52 P59730 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 7548 T1414 C52 P59730 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 7549 T1412 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7549 T1414 C52 P59730 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7552 T1348 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 7553 T1414 C52 P59730 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:34263/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 7554 T1348 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7557 T1348 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7559 T1414 C52 P59730 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 7568 T1414 C52 P59730 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:59730/solr START replicas=[http://127.0.0.1:34263/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 7568 T1414 C52 P59730 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 7569 T1414 C52 P59730 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 7570 T1414 C52 P59730 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 7570 T1414 C52 P59730 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C53 name=collection1 org.apache.solr.core.SolrCore@69da69da url=http://127.0.0.1:34263/solr/collection1 node=127.0.0.1:34263_solr
[junit4:junit4]   2> 7573 T1380 C53 P34263 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> 7697 T1348 oejs.Server.doStart jetty-8.1.7.v20120910
[junit4:junit4]   2> 7698 T1348 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:58058
[junit4:junit4]   2> 7699 T1348 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7700 T1348 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty4-1354471704743
[junit4:junit4]   2> 7700 T1348 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty4-1354471704743/'
[junit4:junit4]   2> 7711 T1348 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 7712 T1348 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7712 T1348 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty4-1354471704743
[junit4:junit4]   2> 7713 T1348 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty4-1354471704743/solr.xml
[junit4:junit4]   2> 7713 T1348 oasc.CoreContainer.<init> New CoreContainer 940521487
[junit4:junit4]   2> 7714 T1348 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty4-1354471704743/'
[junit4:junit4]   2> 7715 T1348 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty4-1354471704743/'
[junit4:junit4]   2> 7728 T1348 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 7737 T1348 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:59899/solr
[junit4:junit4]   2> 7738 T1348 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:59899/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@699f699f
[junit4:junit4]   2> 7739 T1348 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7740 T1425 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:59899. Will not attempt to authenticate using SASL (Access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4]   2> 7740 T1425 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:59899, initiating session
[junit4:junit4]   2> 7740 T1350 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:55962
[junit4:junit4]   2> 7741 T1350 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:55962
[junit4:junit4]   2> 7742 T1352 oazs.ZooKeeperServer.finishSessionInit Established session 0x13b5ccdeb230007 with negotiated timeout 8000 for client /127.0.0.1:55962
[junit4:junit4]   2> 7742 T1425 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:59899, sessionid = 0x13b5ccdeb230007, negotiated timeout = 8000
[junit4:junit4]   2> 7743 T1426 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@699f699f name:ZooKeeperConnection Watcher:127.0.0.1:59899/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 7744 T1348 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 7745 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230007 type:create cxid:0x1 zxid:0x99 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7747 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230007 type:create cxid:0x2 zxid:0x9a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7749 T1348 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58058_solr
[junit4:junit4]   2> 7750 T1353 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13b5ccdeb230007 type:delete cxid:0x4 zxid:0x9b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:58058_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:58058_solr
[junit4:junit4]   2> 7751 T1348 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58058_solr
[junit4:junit4]   2> 7756 T1398 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 7756 T1368 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 7756 T1374 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 7756 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7757 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7757 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7757 T1411 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 7756 T1385 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 7759 T1411 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7760 T1348 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 7760 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7761 T1348 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 7762 T1348 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 7763 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 7764 T1348 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 7765 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 7765 T1348 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 7766 T1348 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 7766 T1348 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 7767 T1348 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 7777 T1348 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 7786 T1427 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty4-1354471704743/collection1
[junit4:junit4]   2> 7787 T1427 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 7789 T1427 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 7789 T1427 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 7792 T1427 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty4-1354471704743/collection1/'
[junit4:junit4]   2> 7793 T1427 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty4-1354471704743/collection1/lib/README' to classloader
[junit4:junit4]   2> 7794 T1427 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty4-1354471704743/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 7829 T1427 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 8019 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8020 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:59730/solr",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:59730_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> 8036 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8036 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8036 T1426 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8036 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8036 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8036 T1411 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8063 T1427 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 8064 T1427 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8070 T1427 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 8407 T1427 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 8412 T1427 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8415 T1427 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8434 T1427 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8438 T1427 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8443 T1427 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8446 T1427 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8448 T1427 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty4-1354471704743/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4/
[junit4:junit4]   2> 8449 T1427 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@434a434a
[junit4:junit4]   2> 8450 T1427 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 8451 T1427 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@4eef4eef; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 8451 T1427 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4 forceNew:false
[junit4:junit4]   2> 8452 T1427 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4
[junit4:junit4]   2> 8453 T1427 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4/index/
[junit4:junit4]   2> 8454 T1427 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 8455 T1427 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4/index forceNew:false
[junit4:junit4]   2> 8461 T1427 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6c2e6c2e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8462 T1427 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8463 T1427 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4/index
[junit4:junit4]   2> 8464 T1427 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 8466 T1427 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 8467 T1427 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 8467 T1427 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 8467 T1427 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 8468 T1427 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 8468 T1427 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 8469 T1427 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 8471 T1427 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 8472 T1427 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 8474 T1427 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 8476 T1427 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 8476 T1427 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 8478 T1427 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 8478 T1427 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 8479 T1427 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 8481 T1427 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 8482 T1427 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8484 T1427 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8485 T1427 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8487 T1427 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8489 T1427 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8490 T1427 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8492 T1427 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8493 T1427 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 8495 T1427 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 8496 T1427 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 8498 T1427 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 8500 T1427 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> 8502 T1427 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4
[junit4:junit4]   2> 8650 T1427 oass.SolrIndexSearcher.<init> Opening Searcher@3ba53ba5 main
[junit4:junit4]   2> 8652 T1427 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 8653 T1427 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8654 T1427 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 8661 T1427 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 8730 T1428 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3ba53ba5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8746 T1427 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9161 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9164 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:58058/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:58058_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> 9165 T1369 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 9165 T1369 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 9177 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9177 T1426 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9178 T1411 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9177 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9178 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9177 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9248 T1427 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 9249 T1427 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:58058/solr shardId:shard2
[junit4:junit4]   2> 9260 T1427 oasc.ZkController.register We are http://127.0.0.1:58058/solr/collection1/ and leader is http://127.0.0.1:58793/solr/collection1/
[junit4:junit4]   2> 9261 T1427 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58058/solr
[junit4:junit4]   2> 9262 T1427 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 9263 T1427 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C54 name=collection1 org.apache.solr.core.SolrCore@7e8a7e8a url=http://127.0.0.1:58058/solr/collection1 node=127.0.0.1:58058_solr
[junit4:junit4]   2> 9264 T1429 C54 P58058 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 9265 T1429 C54 P58058 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 9265 T1427 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9266 T1429 C54 P58058 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9269 T1348 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 9270 T1429 C54 P58058 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:58793/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 9270 T1348 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 9271 T1429 C54 P58058 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:58058/solr START replicas=[http://127.0.0.1:58793/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 9273 T1348 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9273 T1429 C54 P58058 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 9274 T1429 C54 P58058 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 9275 T1429 C54 P58058 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 9276 T1429 C54 P58058 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C55 name=collection1 org.apache.solr.core.SolrCore@6220622 url=http://127.0.0.1:58793/solr/collection1 node=127.0.0.1:58793_solr
[junit4:junit4]   2> 9284 T1393 C55 P58793 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> 9285 T1348 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9287 T1348 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):540
[junit4:junit4]   2> 9289 T1348 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9693 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9695 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:58058/solr",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:58058_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> 9706 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9707 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9707 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9707 T1411 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9707 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9707 T1426 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10292 T1348 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C52_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:59730/solr, state=recovering, node_name=127.0.0.1:59730_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 10651 T1414 C52 P59730 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 10652 T1414 C52 P59730 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 10653 T1414 C52 P59730 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:34263/solr/collection1/. core=collection1
[junit4:junit4]   2> 10654 T1414 C52 P59730 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10665 T1381 C53 P34263 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10666 T1381 C53 P34263 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1
[junit4:junit4]   2> 10671 T1381 C53 P34263 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2250225; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10672 T1381 C53 P34263 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 10673 T1381 C53 P34263 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1
[junit4:junit4]   2> 10673 T1381 C53 P34263 oass.SolrIndexSearcher.<init> Opening Searcher@2c032c03 realtime
[junit4:junit4]   2> 10674 T1381 C53 P34263 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10675 T1381 C53 P34263 /update {softCommit=false&commit_end_point=true&commit=true&version=2&waitSearcher=true&wt=javabin&openSearcher=false} {commit=} 0 11
[junit4:junit4]   2> 10677 T1414 C52 P59730 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 10677 T1414 C52 P59730 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 10682 T1414 C52 P59730 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 10685 T1383 C53 P34263 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 10686 T1383 C53 P34263 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 10687 T1414 C52 P59730 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3
[junit4:junit4]   2> 10690 T1414 C52 P59730 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@61036103; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10691 T1414 C52 P59730 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 10693 T1414 C52 P59730 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10714 T1414 C52 P59730 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@61036103; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@61036103; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 10715 T1414 C52 P59730 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 10716 T1414 C52 P59730 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3
[junit4:junit4]   2> 10721 T1414 C52 P59730 oass.SolrIndexSearcher.<init> Opening Searcher@53785378 main
[junit4:junit4]   2> 10722 T1414 C52 P59730 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10726 T1413 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@53785378 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 10727 T1413 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3/index
[junit4:junit4]   2> 10728 T1414 C52 P59730 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 10729 T1414 C52 P59730 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 10730 T1414 C52 P59730 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10734 T1414 C52 P59730 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 11236 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11239 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:59730/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:59730_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> 11375 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11375 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11375 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11375 T1411 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11376 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11376 T1348 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11375 T1426 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2>  C54_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:58058/solr, state=recovering, node_name=127.0.0.1:58058_solr, roles=null, collection=collection1, shard=shard2, core=collection1}
[junit4:junit4]   2> 12286 T1429 C54 P58058 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 12287 T1429 C54 P58058 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 12287 T1429 C54 P58058 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:58793/solr/collection1/. core=collection1
[junit4:junit4]   2> 12288 T1429 C54 P58058 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12295 T1396 C55 P58793 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12296 T1396 C55 P58793 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2
[junit4:junit4]   2> 12301 T1396 C55 P58793 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@7fc87fc8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12302 T1396 C55 P58793 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 12303 T1396 C55 P58793 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2
[junit4:junit4]   2> 12304 T1396 C55 P58793 oass.SolrIndexSearcher.<init> Opening Searcher@31ef31ef realtime
[junit4:junit4]   2> 12304 T1396 C55 P58793 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12305 T1396 C55 P58793 /update {softCommit=false&commit_end_point=true&commit=true&version=2&waitSearcher=true&wt=javabin&openSearcher=false} {commit=} 0 10
[junit4:junit4]   2> 12307 T1429 C54 P58058 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 12307 T1429 C54 P58058 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 12311 T1395 C55 P58793 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 12312 T1395 C55 P58793 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 12313 T1429 C54 P58058 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4
[junit4:junit4]   2> 12316 T1429 C54 P58058 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6c2e6c2e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12317 T1429 C54 P58058 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 12318 T1429 C54 P58058 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12324 T1429 C54 P58058 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6c2e6c2e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@6c2e6c2e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 12324 T1429 C54 P58058 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 12325 T1429 C54 P58058 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4
[junit4:junit4]   2> 12328 T1429 C54 P58058 oass.SolrIndexSearcher.<init> Opening Searcher@291e291e main
[junit4:junit4]   2> 12328 T1429 C54 P58058 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12330 T1428 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@291e291e main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 12331 T1428 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4/index
[junit4:junit4]   2> 12331 T1429 C54 P58058 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 12332 T1429 C54 P58058 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 12332 T1429 C54 P58058 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12335 T1429 C54 P58058 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 12384 T1348 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12394 T1369 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12396 T1369 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:58058/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:58058_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> 12401 T1374 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12401 T1385 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12401 T1398 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12402 T1368 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12401 T1426 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12401 T1411 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 13386 T1348 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C56 name=collection1 org.apache.solr.core.SolrCore@2dbc2dbc url=http://127.0.0.1:38511/solr/collection1 node=127.0.0.1:38511_solr
[junit4:junit4]   2> 13395 T1365 C56 P38511 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data
[junit4:junit4]   2> 13401 T1365 C56 P38511 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@65426542; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13403 T1365 C56 P38511 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 13405 T1365 C56 P38511 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data
[junit4:junit4]   2> 13406 T1365 C56 P38511 oass.SolrIndexSearcher.<init> Opening Searcher@25b625b6 realtime
[junit4:junit4]   2> 13407 T1365 C56 P38511 /update {version=2&wt=javabin} {deleteByQuery=*:* (-1420266528383696896)} 0 13
[junit4:junit4]   2> 13410 T1394 C55 P58793 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty2
[junit4:junit4]   2> 13419 T1380 C53 P34263 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty1
[junit4:junit4]   2>  C54_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:58058/solr, state=active, node_name=127.0.0.1:58058_solr, roles=null, collection=collection1, shard=shard2, core=collection1}
[junit4:junit4]   2> 13420 T1421 C54 P58058 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty4
[junit4:junit4]   2> 13422 T1421 C54 P58058 oass.SolrIndexSearcher.<init> Opening Searcher@6a136a13 realtime
[junit4:junit4]   2> 13423 T1421 C54 P58058 REQ /update {update.distrib=FROMLEADER&_version_=-1420266528400474112&version=2&wt=javabin&update.from=http://127.0.0.1:58793/solr/collection1/} status=0 QTime=6 
[junit4:junit4]   2>  C52_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:59730/solr, state=active, node_name=127.0.0.1:59730_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 13426 T1405 C52 P59730 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/jetty3
[junit4:junit4]   2> 13427 T1405 C52 P59730 oass.SolrIndexSearcher.<init> Opening Searcher@12791279 realtime
[junit4:junit4]   2> 13428 T1405 C52 P59730 REQ /update {update.distrib=FROMLEADER&_version_=-1420266528407814144&version=2&wt=javabin&update.from=http://127.0.0.1:34263/solr/collection1/} status=0 QTime=3 
[junit4:junit4]   2> 13429 T1380 C53 P34263 REQ /update {update.distrib=TOLEADER&version=2&wt=javabin} status=0 QTime=12 
[junit4:junit4]   2> 13431 T1394 C55 P58793 /update {version=2&wt=javabin} {deleteByQuery=*:* (-1420266528400474112)} 0 22
[junit4:junit4]   2> 13435 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[1 (1420266528424591360)]} 0 2
[junit4:junit4]   2> 13442 T1407 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 13443 T1382 C53 P34263 /update {version=2&wt=javabin} {add=[1 (1420266528428785664)]} 0 6
[junit4:junit4]   2> 13447 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[2 (1420266528438222848)]} 0 1
[junit4:junit4]   2> 13454 T1422 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13455 T1396 C55 P58793 /update {version=2&wt=javabin} {add=[2 (1420266528442417152)]} 0 6
[junit4:junit4]   2> 13458 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[3 (1420266528450805760)]} 0 1
[junit4:junit4]   2> 13466 T1424 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13467 T1393 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 13468 T1383 C53 P34263 /update {version=2&wt=javabin} {add=[3]} 0 7
[junit4:junit4]   2> 13471 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[4 (1420266528464437248)]} 0 1
[junit4:junit4]   2> 13478 T1409 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13480 T1381 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 13481 T1395 C55 P58793 /update {version=2&wt=javabin} {add=[4]} 0 7
[junit4:junit4]   2> 13485 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[5 (1420266528479117312)]} 0 0
[junit4:junit4]   2> 13493 T1422 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 13494 T1394 C55 P58793 /update {version=2&wt=javabin} {add=[5 (1420266528482263040)]} 0 6
[junit4:junit4]   2> 13497 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[6 (1420266528490651648)]} 0 1
[junit4:junit4]   2> 13504 T1423 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13505 T1396 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 13506 T1382 C53 P34263 /update {version=2&wt=javabin} {add=[6]} 0 7
[junit4:junit4]   2> 13510 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[7 (1420266528504283136)]} 0 1
[junit4:junit4]   2> 13515 T1424 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13516 T1393 C55 P58793 /update {version=2&wt=javabin} {add=[7 (1420266528507428864)]} 0 4
[junit4:junit4]   2> 13519 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[8 (1420266528513720320)]} 0 1
[junit4:junit4]   2> 13524 T1408 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 13525 T1381 C53 P34263 /update {version=2&wt=javabin} {add=[8 (1420266528516866048)]} 0 4
[junit4:junit4]   2> 13529 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[9 (1420266528524206080)]} 0 1
[junit4:junit4]   2> 13536 T1421 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13537 T1395 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 13538 T1380 C53 P34263 /update {version=2&wt=javabin} {add=[9]} 0 7
[junit4:junit4]   2> 13667 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[10 (1420266528536788992)]} 0 127
[junit4:junit4]   2> 13675 T1405 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13676 T1383 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 13677 T1396 C55 P58793 /update {version=2&wt=javabin} {add=[10]} 0 7
[junit4:junit4]   2> 13681 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[11 (1420266528683589632)]} 0 2
[junit4:junit4]   2> 13688 T1409 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13689 T1382 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 13689 T1393 C55 P58793 /update {version=2&wt=javabin} {add=[11]} 0 6
[junit4:junit4]   2> 13692 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[12 (1420266528696172544)]} 0 0
[junit4:junit4]   2> 13699 T1408 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13699 T1381 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 13700 T1394 C55 P58793 /update {version=2&wt=javabin} {add=[12]} 0 5
[junit4:junit4]   2> 13703 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[13 (1420266528707706880)]} 0 0
[junit4:junit4]   2> 13708 T1407 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 13709 T1380 C53 P34263 /update {version=2&wt=javabin} {add=[13 (1420266528710852608)]} 0 3
[junit4:junit4]   2> 13712 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[14 (1420266528716095488)]} 0 1
[junit4:junit4]   2> 13718 T1405 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 13719 T1383 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 13720 T1395 C55 P58793 /update {version=2&wt=javabin} {add=[14]} 0 6
[junit4:junit4]   2> 13723 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[15 (1420266528727629824)]} 0 1
[junit4:junit4]   2> 13728 T1409 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13728 T1382 C53 P34263 /update {version=2&wt=javabin} {add=[15 (1420266528730775552)]} 0 3
[junit4:junit4]   2> 13736 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[16 (1420266528741261312)]} 0 1
[junit4:junit4]   2> 13757 T1408 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=7 
[junit4:junit4]   2> 13758 T1381 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=17 
[junit4:junit4]   2> 13759 T1396 C55 P58793 /update {version=2&wt=javabin} {add=[16]} 0 21
[junit4:junit4]   2> 13763 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[17 (1420266528769572864)]} 0 2
[junit4:junit4]   2> 13773 T1423 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 13775 T1393 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=8 
[junit4:junit4]   2> 13776 T1380 C53 P34263 /update {version=2&wt=javabin} {add=[17]} 0 10
[junit4:junit4]   2> 13778 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[100 (1420266528786350080)]} 0 0
[junit4:junit4]   2> 13788 T1407 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 13789 T1383 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=7 
[junit4:junit4]   2> 13790 T1394 C55 P58793 /update {version=2&wt=javabin} {add=[100]} 0 9
[junit4:junit4]   2> 13793 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[101 (1420266528801030144)]} 0 1
[junit4:junit4]   2> 13797 T1405 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 13798 T1382 C53 P34263 /update {version=2&wt=javabin} {add=[101 (1420266528804175872)]} 0 3
[junit4:junit4]   2> 13807 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[102 (1420266528809418752)]} 0 7
[junit4:junit4]   2> 13814 T1409 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13815 T1381 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 13816 T1395 C55 P58793 /update {version=2&wt=javabin} {add=[102]} 0 6
[junit4:junit4]   2> 13819 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[103 (1420266528828293120)]} 0 1
[junit4:junit4]   2> 13826 T1424 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 13827 T1393 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 13828 T1380 C53 P34263 /update {version=2&wt=javabin} {add=[103]} 0 6
[junit4:junit4]   2> 13831 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[104 (1420266528840876032)]} 0 1
[junit4:junit4]   2> 13836 T1422 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 13837 T1394 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 13838 T1383 C53 P34263 /update {version=2&wt=javabin} {add=[104]} 0 5
[junit4:junit4]   2> 13841 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[105 (1420266528851361792)]} 0 1
[junit4:junit4]   2> 14015 T1423 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=6 
[junit4:junit4]   2> 14016 T1396 C55 P58793 /update {version=2&wt=javabin} {add=[105 (1420266528854507520)]} 0 173
[junit4:junit4]   2> 14018 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[106 (1420266529038008320)]} 0 0
[junit4:junit4]   2> 14024 T1421 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14025 T1395 C55 P58793 /update {version=2&wt=javabin} {add=[106 (1420266529041154048)]} 0 4
[junit4:junit4]   2> 14028 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[107 (1420266529047445504)]} 0 1
[junit4:junit4]   2> 14034 T1407 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14035 T1382 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14036 T1393 C55 P58793 /update {version=2&wt=javabin} {add=[107]} 0 6
[junit4:junit4]   2> 14038 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[108 (1420266529058979840)]} 0 0
[junit4:junit4]   2> 14043 T1405 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14044 T1380 C53 P34263 /update {version=2&wt=javabin} {add=[108 (1420266529062125568)]} 0 4
[junit4:junit4]   2> 14047 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[109 (1420266529067368448)]} 0 1
[junit4:junit4]   2> 14053 T1409 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14054 T1381 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 14055 T1394 C55 P58793 /update {version=2&wt=javabin} {add=[109]} 0 6
[junit4:junit4]   2> 14057 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[110 (1420266529078902784)]} 0 0
[junit4:junit4]   2> 14062 T1408 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14063 T1382 C53 P34263 /update {version=2&wt=javabin} {add=[110 (1420266529082048512)]} 0 4
[junit4:junit4]   2> 14066 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[111 (1420266529087291392)]} 0 1
[junit4:junit4]   2> 14070 T1407 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14071 T1383 C53 P34263 /update {version=2&wt=javabin} {add=[111 (1420266529090437120)]} 0 3
[junit4:junit4]   2> 14077 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[112 (1420266529095680000)]} 0 4
[junit4:junit4]   2> 14084 T1424 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14084 T1396 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14085 T1380 C53 P34263 /update {version=2&wt=javabin} {add=[112]} 0 5
[junit4:junit4]   2> 14089 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[113 (1420266529111408640)]} 0 1
[junit4:junit4]   2> 14095 T1405 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14095 T1381 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14096 T1393 C55 P58793 /update {version=2&wt=javabin} {add=[113]} 0 5
[junit4:junit4]   2> 14099 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[114 (1420266529121894400)]} 0 1
[junit4:junit4]   2> 14116 T1408 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=7 
[junit4:junit4]   2> 14117 T1382 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=14 
[junit4:junit4]   2> 14118 T1394 C55 P58793 /update {version=2&wt=javabin} {add=[114]} 0 17
[junit4:junit4]   2> 14121 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[115 (1420266529144963072)]} 0 1
[junit4:junit4]   2> 14129 T1422 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14131 T1395 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 14132 T1383 C53 P34263 /update {version=2&wt=javabin} {add=[115]} 0 8
[junit4:junit4]   2> 14134 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[116 (1420266529159643136)]} 0 0
[junit4:junit4]   2> 14139 T1423 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14140 T1396 C55 P58793 /update {version=2&wt=javabin} {add=[116 (1420266529162788864)]} 0 3
[junit4:junit4]   2> 14142 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[117 (1420266529168031744)]} 0 0
[junit4:junit4]   2> 14148 T1421 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14149 T1393 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14150 T1380 C53 P34263 /update {version=2&wt=javabin} {add=[117]} 0 6
[junit4:junit4]   2> 14153 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[118 (1420266529179566080)]} 0 0
[junit4:junit4]   2> 14160 T1407 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14161 T1381 C53 P34263 /update {version=2&wt=javabin} {add=[118 (1420266529182711808)]} 0 5
[junit4:junit4]   2> 14164 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[119 (1420266529190051840)]} 0 1
[junit4:junit4]   2> 14170 T1424 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14171 T1394 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14172 T1382 C53 P34263 /update {version=2&wt=javabin} {add=[119]} 0 6
[junit4:junit4]   2> 14174 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[120 (1420266529201586176)]} 0 0
[junit4:junit4]   2> 14180 T1422 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14181 T1395 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14183 T1383 C53 P34263 /update {version=2&wt=javabin} {add=[120]} 0 6
[junit4:junit4]   2> 14185 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[121 (1420266529213120512)]} 0 0
[junit4:junit4]   2> 14190 T1423 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14191 T1396 C55 P58793 /update {version=2&wt=javabin} {add=[121 (1420266529216266240)]} 0 4
[junit4:junit4]   2> 14200 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[122 (1420266529221509120)]} 0 7
[junit4:junit4]   2> 14206 T1409 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14207 T1380 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14208 T1393 C55 P58793 /update {version=2&wt=javabin} {add=[122]} 0 6
[junit4:junit4]   2> 14211 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[123 (1420266529239334912)]} 0 1
[junit4:junit4]   2> 14216 T1405 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14216 T1381 C53 P34263 /update {version=2&wt=javabin} {add=[123 (1420266529242480640)]} 0 3
[junit4:junit4]   2> 14219 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[124 (1420266529248772096)]} 0 0
[junit4:junit4]   2> 14223 T1408 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14224 T1382 C53 P34263 /update {version=2&wt=javabin} {add=[124 (1420266529250869248)]} 0 3
[junit4:junit4]   2> 14227 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[125 (1420266529257160704)]} 0 1
[junit4:junit4]   2> 14233 T1407 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14234 T1383 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14234 T1394 C55 P58793 /update {version=2&wt=javabin} {add=[125]} 0 5
[junit4:junit4]   2> 14237 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[126 (1420266529267646464)]} 0 1
[junit4:junit4]   2> 14241 T1421 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14242 T1396 C55 P58793 /update {version=2&wt=javabin} {add=[126 (1420266529269743616)]} 0 3
[junit4:junit4]   2> 14245 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[127 (1420266529274986496)]} 0 1
[junit4:junit4]   2> 14258 T1422 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 14259 T1393 C55 P58793 /update {version=2&wt=javabin} {add=[127 (1420266529278132224)]} 0 12
[junit4:junit4]   2> 14261 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[128 (1420266529292812288)]} 0 0
[junit4:junit4]   2> 14269 T1423 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 14269 T1395 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 14270 T1380 C53 P34263 /update {version=2&wt=javabin} {add=[128]} 0 6
[junit4:junit4]   2> 14278 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[129 (1420266529309589504)]} 0 1
[junit4:junit4]   2> 14283 T1409 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14284 T1381 C53 P34263 /update {version=2&wt=javabin} {add=[129 (1420266529313783808)]} 0 3
[junit4:junit4]   2> 14287 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[130 (1420266529320075264)]} 0 0
[junit4:junit4]   2> 14293 T1424 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14294 T1394 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14295 T1382 C53 P34263 /update {version=2&wt=javabin} {add=[130]} 0 6
[junit4:junit4]   2> 14298 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[131 (1420266529330561024)]} 0 1
[junit4:junit4]   2> 14303 T1421 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14304 T1396 C55 P58793 /update {version=2&wt=javabin} {add=[131 (1420266529333706752)]} 0 4
[junit4:junit4]   2> 14311 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[132 (1420266529339998208)]} 0 5
[junit4:junit4]   2> 14317 T1405 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14318 T1383 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14319 T1393 C55 P58793 /update {version=2&wt=javabin} {add=[132]} 0 6
[junit4:junit4]   2> 14322 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[133 (1420266529355726848)]} 0 1
[junit4:junit4]   2> 14328 T1408 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14329 T1380 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14330 T1395 C55 P58793 /update {version=2&wt=javabin} {add=[133]} 0 6
[junit4:junit4]   2> 14332 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[134 (1420266529367261184)]} 0 0
[junit4:junit4]   2> 14337 T1422 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14338 T1394 C55 P58793 /update {version=2&wt=javabin} {add=[134 (1420266529370406912)]} 0 3
[junit4:junit4]   2> 14341 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[135 (1420266529375649792)]} 0 1
[junit4:junit4]   2> 14346 T1407 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14346 T1381 C53 P34263 /update {version=2&wt=javabin} {add=[135 (1420266529378795520)]} 0 3
[junit4:junit4]   2> 14349 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[136 (1420266529385086976)]} 0 1
[junit4:junit4]   2> 14355 T1423 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14356 T1396 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14357 T1382 C53 P34263 /update {version=2&wt=javabin} {add=[136]} 0 6
[junit4:junit4]   2> 14359 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[137 (1420266529395572736)]} 0 0
[junit4:junit4]   2> 14365 T1424 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14366 T1393 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14367 T1383 C53 P34263 /update {version=2&wt=javabin} {add=[137]} 0 6
[junit4:junit4]   2> 14369 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[138 (1420266529406058496)]} 0 0
[junit4:junit4]   2> 14384 T1409 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 14385 T1380 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=12 
[junit4:junit4]   2> 14386 T1395 C55 P58793 /update {version=2&wt=javabin} {add=[138]} 0 14
[junit4:junit4]   2> 14389 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[139 (1420266529425981440)]} 0 1
[junit4:junit4]   2> 14394 T1421 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14395 T1394 C55 P58793 /update {version=2&wt=javabin} {add=[139 (1420266529429127168)]} 0 4
[junit4:junit4]   2> 14397 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[140 (1420266529435418624)]} 0 0
[junit4:junit4]   2> 14403 T1422 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14404 T1396 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14405 T1381 C53 P34263 /update {version=2&wt=javabin} {add=[140]} 0 6
[junit4:junit4]   2> 14407 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[141 (1420266529445904384)]} 0 0
[junit4:junit4]   2> 14412 T1423 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14413 T1393 C55 P58793 /update {version=2&wt=javabin} {add=[141 (1420266529449050112)]} 0 3
[junit4:junit4]   2> 14419 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[142 (1420266529454292992)]} 0 4
[junit4:junit4]   2> 14432 T1424 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 14433 T1395 C55 P58793 /update {version=2&wt=javabin} {add=[142 (1420266529461633024)]} 0 11
[junit4:junit4]   2> 14436 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[143 (1420266529475264512)]} 0 1
[junit4:junit4]   2> 14443 T1421 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 14444 T1394 C55 P58793 /update {version=2&wt=javabin} {add=[143 (1420266529479458816)]} 0 6
[junit4:junit4]   2> 14446 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[144 (1420266529486798848)]} 0 0
[junit4:junit4]   2> 14455 T1422 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14457 T1396 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 14458 T1382 C53 P34263 /update {version=2&wt=javabin} {add=[144]} 0 9
[junit4:junit4]   2> 14461 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[145 (1420266529502527488)]} 0 0
[junit4:junit4]   2> 14468 T1423 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14468 T1393 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 14469 T1380 C53 P34263 /update {version=2&wt=javabin} {add=[145]} 0 5
[junit4:junit4]   2> 14472 T1366 C56 P38511 /update {version=2&wt=javabin} {add=[146 (1420266529513013248)]} 0 1
[junit4:junit4]   2> 14479 T1408 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14480 T1381 C53 P34263 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=5 
[junit4:junit4]   2> 14480 T1395 C55 P58793 /update {version=2&wt=javabin} {add=[146]} 0 6
[junit4:junit4]   2> 14483 T1365 C56 P38511 /update {version=2&wt=javabin} {add=[147 (1420266529525596160)]} 0 0
[junit4:junit4]   2> 14488 T1424 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14489 T1394 C55 P58793 /update {version=2&wt=javabin} {add=[147 (1420266529528741888)]} 0 3
[junit4:junit4]   2> 14492 T1364 C56 P38511 /update {version=2&wt=javabin} {add=[148 (1420266529533984768)]} 0 1
[junit4:junit4]   2> 14496 T1407 C52 P59730 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 14497 T1382 C53 P34263 /update {version=2&wt=javabin} {add=[148 (1420266529537130496)]} 0 3
[junit4:junit4]   2> 14500 T1363 C56 P38511 /update {version=2&wt=javabin} {add=[149 (1420266529542373376)]} 0 1
[junit4:junit4]   2> 14506 T1422 C54 P58058 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:58793/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 14507 T1396 C55 P58793 REQ /update {update.distrib=TOLEADER&distrib.from=http://127.0.0.1:34263/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 14508 T1380 C53 P34263 /update {version=2&wt=javabin} {add=[149]} 0 6
[junit4:junit4]   2> 14510 T1366 C56 P38511 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14774 T1366 C56 P38511 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@65426542; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1354471697181/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@65426542; maxCacheMB=48.0 maxMergeSizeMB=4.0),seg

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

nit4:junit4]   1>               "127.0.0.1:58058_solr_unloadcollection4":{
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:58058/solr",
[junit4:junit4]   1>                 "state":"active",
[junit4:junit4]   1>                 "node_name":"127.0.0.1:58058_solr",
[junit4:junit4]   1>                 "roles":null,
[junit4:junit4]   1>                 "collection":"unloadcollection",
[junit4:junit4]   1>                 "shard":"shard1",
[junit4:junit4]   1>                 "core":"unloadcollection4"},
[junit4:junit4]   1>               "127.0.0.1:58793_solr_unloadcollection2":{
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:58793/solr",
[junit4:junit4]   1>                 "state":"active",
[junit4:junit4]   1>                 "node_name":"127.0.0.1:58793_solr",
[junit4:junit4]   1>                 "roles":null,
[junit4:junit4]   1>                 "collection":"unloadcollection",
[junit4:junit4]   1>                 "shard":"shard1",
[junit4:junit4]   1>                 "core":"unloadcollection2"}}}}}
[junit4:junit4]   1>  /zookeeper (1)
[junit4:junit4]   1>  DATA:
[junit4:junit4]   1>      
[junit4:junit4]   1> 
[junit4:junit4]   2> 467540 T1348 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 467823 T1351 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> 468343 T1426 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 468344 T1426 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 468345 T1426 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 468345 T1426 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 468346 T1426 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 588883 T1348 oas.SolrTestCaseJ4.endTrackingSearchers SEVERE ERROR: SolrIndexSearcher opens=1597 closes=1596
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {author_t=Lucene41(blocksize=128), multiDefault=PostingsFormat(name=Lucene41WithOrds), price=Pulsing41(freqCutoff=17 minBlockSize=88 maxBlockSize=227), other_tl1=Lucene41(blocksize=128), val_i=PostingsFormat(name=Memory doPackFST= false), name=PostingsFormat(name=Memory doPackFST= false), n_ti1=PostingsFormat(name=Memory doPackFST= false), rnd_b=PostingsFormat(name=Memory doPackFST= false), SubjectTerms_mfacet=Lucene41(blocksize=128), n_dt1=Lucene41(blocksize=128), intDefault=Lucene41(blocksize=128), cat=PostingsFormat(name=Lucene41WithOrds), a_t=PostingsFormat(name=Lucene41WithOrds), timestamp=PostingsFormat(name=Lucene41WithOrds), id=PostingsFormat(name=Memory doPackFST= false), n_tdt1=Lucene41(blocksize=128), range_facet_si=PostingsFormat(name=Lucene41WithOrds), range_facet_l=PostingsFormat(name=Lucene41WithOrds), range_facet_sl=PostingsFormat(name=Memory doPackFST= false), _version_=Lucene41(blocksize=128), n_tl1=Lucene41(blocksize=128), foo_b=Pulsing41(freqCutoff=17 minBlockSize=88 maxBlockSize=227), sequence_i=PostingsFormat(name=Memory doPackFST= false), foo_d=PostingsFormat(name=Memory doPackFST= false), series_t=Lucene41(blocksize=128), foo_f=Pulsing41(freqCutoff=17 minBlockSize=88 maxBlockSize=227), text=Pulsing41(freqCutoff=17 minBlockSize=88 maxBlockSize=227), n_tf1=PostingsFormat(name=Lucene41WithOrds), foo_i=Lucene41(blocksize=128), n_td1=Lucene41(blocksize=128), inStock=Pulsing41(freqCutoff=17 minBlockSize=88 maxBlockSize=227), a_si=Lucene41(blocksize=128), n_l1=PostingsFormat(name=Lucene41WithOrds), genre_s=PostingsFormat(name=Memory doPackFST= false), n_f1=Lucene41(blocksize=128), regex_dup_A_s=Pulsing41(freqCutoff=17 minBlockSize=88 maxBlockSize=227), regex_dup_B_s=PostingsFormat(name=Lucene41WithOrds), n_d1=PostingsFormat(name=Lucene41WithOrds), oddField_s=PostingsFormat(name=Memory doPackFST= false)}, sim=DefaultSimilarity, locale=lt_LT, timezone=America/Bahia_Banderas
[junit4:junit4]   2> NOTE: Linux 3.2.0-34-generic amd64/IBM Corporation 1.6.0 (64-bit)/cpus=8,threads=1,free=112965928,total=536870912
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TermVectorComponentDistributedTest, MinimalSchemaTest, SimpleFacetsTest, BadComponentTest, TermsComponentTest, TestCharFilters, TestGroupingSearch, TestRandomFaceting, TestSolrJ, CSVRequestHandlerTest, SystemInfoHandlerTest, ZkControllerTest, TestFastOutputStream, TestNumberUtils, HighlighterConfigTest, UpdateRequestProcessorFactoryTest, TestWordDelimiterFilterFactory, TestLuceneMatchVersion, WordBreakSolrSpellCheckerTest, CurrencyFieldTest, TestFastLRUCache, TestRangeQuery, FastVectorHighlighterTest, PathHierarchyTokenizerFactoryTest, NoCacheHeaderTest, TestLazyCores, PreAnalyzedFieldTest, FieldAnalysisRequestHandlerTest, SampleTest, TestSolrIndexConfig, TestStressVersions, ClusterStateUpdateTest, TestLMJelinekMercerSimilarityFactory, SuggesterWFSTTest, SpellPossibilityIteratorTest, TestPerFieldSimilarity, SOLR749Test, TestExtendedDismaxParser, DirectSolrConnectionTest, SearchHandlerTest, TestIBSimilarityFactory, TestConfig, DateMathParserTest, TestIndexingPerformance, TestJmxMonitoredMap, MoreLikeThisHandlerTest, TestQueryTypes, ResponseLogComponentTest, DistributedTermsComponentTest, TestSystemIdResolver, SolrRequestParserTest, SolrCmdDistributorTest, SortByFunctionTest, SchemaVersionSpecificBehaviorTest, TestStressReorder, TestBadConfig, BasicDistributedZkTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=BasicDistributedZkTest -Dtests.seed=BF445EDC8BB63CD -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lt_LT -Dtests.timezone=America/Bahia_Banderas -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR   0.00s J0 | BasicDistributedZkTest (suite) <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=1597 closes=1596
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([BF445EDC8BB63CD]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:243)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:97)
[junit4:junit4]    > 	at sun.reflect.GeneratedMethodAccessor25.invoke(Unknown Source)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.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$5.evaluate(RandomizedRunner.java:700)
[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] Completed on J0 in 588.94s, 1 test, 1 failure <<< FAILURES!

[...truncated 134 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/solr/common-build.xml:438: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1163: 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: 236 suites, 980 tests, 1 suite-level error, 7 ignored (2 assumptions)

Total time: 35 minutes 8 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/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