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-Windows (32bit/jdk1.7.0_06) - Build # 535 - Failure!
Date Thu, 30 Aug 2012 20:07:32 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows/535/
Java: 32bit/jdk1.7.0_06 -server -XX:+UseConcMarkSweepGC

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

Error Message:
ERROR: SolrIndexSearcher opens=540 closes=539

Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=540 closes=539
	at __randomizedtesting.SeedInfo.seed([DEF869E92B89F42C]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:223)
	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:89)
	at sun.reflect.GeneratedMethodAccessor16.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
	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:345)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 7000 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 3 T2501 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-BasicDistributedZkTest-1346356653044
[junit4:junit4]   2> 4 T2501 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 7 T2502 oazs.ZooKeeperServer.setTickTime tickTime set to 3000
[junit4:junit4]   2> 8 T2502 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 9 T2502 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 83 T2501 oasc.ZkTestServer.run start zk server on port:54461
[junit4:junit4]   2> 83 T2501 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54461 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@3569fb
[junit4:junit4]   2> 84 T2507 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54461
[junit4:junit4]   2> 84 T2501 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 85 T2507 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:54461, initiating session
[junit4:junit4]   2> 85 T2503 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54464
[junit4:junit4]   2> 85 T2503 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54464
[junit4:junit4]   2> 86 T2505 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 89 T2505 oazs.NIOServerCnxn.finishSessionInit Established session 0x139791c2bfb0000 with negotiated timeout 10000 for client /127.0.0.1:54464
[junit4:junit4]   2> 89 T2507 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:54461, sessionid = 0x139791c2bfb0000, negotiated timeout = 10000
[junit4:junit4]   2> 90 T2508 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3569fb name:ZooKeeperConnection Watcher:127.0.0.1:54461 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 90 T2501 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 90 T2501 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 94 T2506 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139791c2bfb0000
[junit4:junit4]   2> 95 T2501 oaz.ZooKeeper.close Session: 0x139791c2bfb0000 closed
[junit4:junit4]   2> 95 T2508 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 95 T2501 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54461/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@6fdba2
[junit4:junit4]   2> 97 T2503 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54464 which had sessionid 0x139791c2bfb0000
[junit4:junit4]   2> 98 T2501 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 98 T2509 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54461
[junit4:junit4]   2> 99 T2509 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:54461, initiating session
[junit4:junit4]   2> 99 T2503 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54467
[junit4:junit4]   2> 99 T2503 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54467
[junit4:junit4]   2> 101 T2505 oazs.NIOServerCnxn.finishSessionInit Established session 0x139791c2bfb0001 with negotiated timeout 10000 for client /127.0.0.1:54467
[junit4:junit4]   2> 101 T2509 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:54461, sessionid = 0x139791c2bfb0001, negotiated timeout = 10000
[junit4:junit4]   2> 101 T2510 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6fdba2 name:ZooKeeperConnection Watcher:127.0.0.1:54461/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 101 T2501 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 101 T2501 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 106 T2501 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 110 T2501 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 112 T2501 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 116 T2501 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 117 T2501 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 123 T2501 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 124 T2501 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 129 T2501 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 129 T2501 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 133 T2501 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 133 T2501 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 137 T2501 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 137 T2501 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 140 T2501 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 141 T2501 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 145 T2501 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 145 T2501 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 148 T2501 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 148 T2501 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 151 T2501 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 152 T2501 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 155 T2501 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 156 T2501 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 159 T2506 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139791c2bfb0001
[junit4:junit4]   2> 162 T2510 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 162 T2503 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139791c2bfb0001, likely client has closed socket
[junit4:junit4]   2> 163 T2503 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:54467 which had sessionid 0x139791c2bfb0001
[junit4:junit4]   2> 162 T2501 oaz.ZooKeeper.close Session: 0x139791c2bfb0001 closed
[junit4:junit4]   2> 349 T2501 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 352 T2501 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:54468
[junit4:junit4]   2> 353 T2501 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 353 T2501 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1
[junit4:junit4]   2> 353 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\'
[junit4:junit4]   2> 385 T2501 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 385 T2501 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 385 T2501 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1
[junit4:junit4]   2> 386 T2501 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\solr.xml
[junit4:junit4]   2> 386 T2501 oasc.CoreContainer.<init> New CoreContainer 31943018
[junit4:junit4]   2> 387 T2501 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\'
[junit4:junit4]   2> 387 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\'
[junit4:junit4]   2> 406 T2501 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 421 T2501 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54461/solr
[junit4:junit4]   2> 422 T2501 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54461/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@17af3
[junit4:junit4]   2> 423 T2520 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54461
[junit4:junit4]   2> 423 T2501 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 423 T2520 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:54461, initiating session
[junit4:junit4]   2> 423 T2503 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54471
[junit4:junit4]   2> 424 T2503 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54471
[junit4:junit4]   2> 430 T2505 oazs.NIOServerCnxn.finishSessionInit Established session 0x139791c2bfb0002 with negotiated timeout 8000 for client /127.0.0.1:54471
[junit4:junit4]   2> 430 T2520 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:54461, sessionid = 0x139791c2bfb0002, negotiated timeout = 8000
[junit4:junit4]   2> 430 T2521 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17af3 name:ZooKeeperConnection Watcher:127.0.0.1:54461/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 430 T2501 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 433 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 435 T2501 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 437 T2501 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54468_solr
[junit4:junit4]   2> 438 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:54468_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:54468_solr
[junit4:junit4]   2> 439 T2501 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54468_solr
[junit4:junit4]   2> 442 T2501 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 442 T2501 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 442 T2501 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 442 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 442 T2501 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 442 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 443 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 443 T2501 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 443 T2501 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 444 T2501 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 451 T2501 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 456 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 457 T2501 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 459 T2501 oasc.Overseer.start Overseer (id=88234829614350338-127.0.0.1:54468_solr-n_0000000000) starting
[junit4:junit4]   2> 460 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 461 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 462 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 464 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 465 T2523 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 465 T2501 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 468 T2501 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 470 T2522 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 474 T2501 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1
[junit4:junit4]   2> 474 T2501 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 475 T2501 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 475 T2501 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 477 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\'
[junit4:junit4]   2> 477 T2501 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/control1/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 478 T2501 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/control1/collection1/lib/README' to classloader
[junit4:junit4]   2> 520 T2501 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 578 T2501 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 579 T2501 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 583 T2501 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1108 T2501 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1115 T2501 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1118 T2501 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1133 T2501 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1136 T2501 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1140 T2501 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1141 T2501 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1142 T2501 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\
[junit4:junit4]   2> 1143 T2501 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1813820
[junit4:junit4]   2> 1144 T2501 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index/
[junit4:junit4]   2> 1144 T2501 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1145 T2501 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index forceNew:false
[junit4:junit4]   2> 1150 T2501 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1150 T2501 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1151 T2501 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1153 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1153 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1153 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1153 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1153 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1154 T2501 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1154 T2501 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1155 T2501 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1156 T2501 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1156 T2501 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1156 T2501 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1157 T2501 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1157 T2501 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1157 T2501 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1157 T2501 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1158 T2501 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1158 T2501 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1159 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1159 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1159 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1159 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1159 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1160 T2501 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1161 T2501 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1162 T2501 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1164 T2501 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1167 T2501 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1169 T2501 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 1173 T2501 oass.SolrIndexSearcher.<init> Opening Searcher@39790 main
[junit4:junit4]   2> 1175 T2501 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1175 T2501 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1176 T2501 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1178 T2501 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1192 T2524 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@39790 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1194 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1199 T2501 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1199 T2501 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:54468/solr shardId:control_shard
[junit4:junit4]   2> 1201 T2501 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1207 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:delete cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 1209 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1211 T2501 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Running the leader process. afterExperiation=false
[junit4:junit4]   2> 1254 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1254 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:create cxid:0x6a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1265 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1989 T2501 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1989 T2501 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54468/solr/collection1/
[junit4:junit4]   2> 2410 T2501 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 2416 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0002 type:create cxid:0x8d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2476 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2481 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2505 T2501 oasc.ZkController.register We are http://127.0.0.1:54468/solr/collection1/ and leader is http://127.0.0.1:54468/solr/collection1/
[junit4:junit4]   2> 2505 T2501 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54468/solr
[junit4:junit4]   2> 2505 T2501 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2508 T2501 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2508 T2501 oass.SolrDispatchFilter.init user.dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 2508 T2501 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2509 T2501 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2728 T2501 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2731 T2501 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:54472
[junit4:junit4]   2> 2732 T2501 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2732 T2501 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2
[junit4:junit4]   2> 2733 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\'
[junit4:junit4]   2> 2768 T2501 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2769 T2501 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2769 T2501 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2
[junit4:junit4]   2> 2769 T2501 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\solr.xml
[junit4:junit4]   2> 2770 T2501 oasc.CoreContainer.<init> New CoreContainer 24360828
[junit4:junit4]   2> 2770 T2501 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\'
[junit4:junit4]   2> 2770 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\'
[junit4:junit4]   2> 2792 T2501 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2808 T2501 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54461/solr
[junit4:junit4]   2> 2808 T2501 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54461/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@2165fe
[junit4:junit4]   2> 2810 T2534 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54461
[junit4:junit4]   2> 2810 T2501 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2811 T2534 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:54461, initiating session
[junit4:junit4]   2> 2812 T2503 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54475
[junit4:junit4]   2> 2812 T2503 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54475
[junit4:junit4]   2> 2817 T2505 oazs.NIOServerCnxn.finishSessionInit Established session 0x139791c2bfb0003 with negotiated timeout 8000 for client /127.0.0.1:54475
[junit4:junit4]   2> 2817 T2534 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:54461, sessionid = 0x139791c2bfb0003, negotiated timeout = 8000
[junit4:junit4]   2> 2818 T2535 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2165fe name:ZooKeeperConnection Watcher:127.0.0.1:54461/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2818 T2501 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2819 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2821 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2823 T2501 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54472_solr
[junit4:junit4]   2> 2823 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:54472_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:54472_solr
[junit4:junit4]   2> 2824 T2501 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54472_solr
[junit4:junit4]   2> 2827 T2521 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2828 T2501 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2828 T2501 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2828 T2501 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2828 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2828 T2501 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2828 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2829 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2829 T2501 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2829 T2501 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2830 T2501 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2839 T2501 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2844 T2501 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1
[junit4:junit4]   2> 2845 T2501 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2845 T2501 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2846 T2501 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2847 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\'
[junit4:junit4]   2> 2848 T2501 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty2/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2849 T2501 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty2/collection1/lib/README' to classloader
[junit4:junit4]   2> 2897 T2501 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2912 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2925 T2535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2925 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2963 T2501 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2964 T2501 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2968 T2501 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3610 T2501 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3619 T2501 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3626 T2501 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3641 T2501 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3645 T2501 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3650 T2501 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3650 T2501 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3652 T2501 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\
[junit4:junit4]   2> 3653 T2501 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1813820
[junit4:junit4]   2> 3654 T2501 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index/
[junit4:junit4]   2> 3655 T2501 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3655 T2501 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index forceNew:false
[junit4:junit4]   2> 3661 T2501 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3661 T2501 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3662 T2501 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3664 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3664 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3664 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3665 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3665 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3665 T2501 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3666 T2501 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3666 T2501 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3666 T2501 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3666 T2501 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3667 T2501 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3667 T2501 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3667 T2501 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3667 T2501 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3668 T2501 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3668 T2501 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3668 T2501 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3669 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3669 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3669 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3670 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3670 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3671 T2501 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3671 T2501 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3671 T2501 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3674 T2501 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3678 T2501 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3680 T2501 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 3682 T2501 oass.SolrIndexSearcher.<init> Opening Searcher@1fd2d93 main
[junit4:junit4]   2> 3684 T2501 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3684 T2501 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3685 T2501 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3687 T2501 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3701 T2536 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1fd2d93 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3706 T2501 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3707 T2501 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:54472/solr shardId:shard3
[junit4:junit4]   2> 3707 T2501 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
[junit4:junit4]   2> 3717 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0003 type:delete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 3724 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0003 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3727 T2501 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Running the leader process. afterExperiation=false
[junit4:junit4]   2> 3787 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3798 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3799 T2535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4583 T2501 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4583 T2501 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54472/solr/collection1/
[junit4:junit4]   2> 5011 T2501 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
[junit4:junit4]   2> 5016 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0003 type:create cxid:0x4d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5093 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5101 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5101 T2535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5107 T2501 oasc.ZkController.register We are http://127.0.0.1:54472/solr/collection1/ and leader is http://127.0.0.1:54472/solr/collection1/
[junit4:junit4]   2> 5107 T2501 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54472/solr
[junit4:junit4]   2> 5108 T2501 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 5110 T2501 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5111 T2501 oass.SolrDispatchFilter.init user.dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 5111 T2501 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5111 T2501 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5311 T2501 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5313 T2501 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:54476
[junit4:junit4]   2> 5314 T2501 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5314 T2501 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3
[junit4:junit4]   2> 5315 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\'
[junit4:junit4]   2> 5343 T2501 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5343 T2501 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5343 T2501 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3
[junit4:junit4]   2> 5344 T2501 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\solr.xml
[junit4:junit4]   2> 5344 T2501 oasc.CoreContainer.<init> New CoreContainer 17183001
[junit4:junit4]   2> 5344 T2501 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\'
[junit4:junit4]   2> 5345 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\'
[junit4:junit4]   2> 5370 T2501 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5382 T2501 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54461/solr
[junit4:junit4]   2> 5382 T2501 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54461/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@70729
[junit4:junit4]   2> 5384 T2546 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54461
[junit4:junit4]   2> 5384 T2501 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5385 T2546 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:54461, initiating session
[junit4:junit4]   2> 5385 T2503 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54479
[junit4:junit4]   2> 5385 T2503 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54479
[junit4:junit4]   2> 5392 T2505 oazs.NIOServerCnxn.finishSessionInit Established session 0x139791c2bfb0004 with negotiated timeout 8000 for client /127.0.0.1:54479
[junit4:junit4]   2> 5393 T2546 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:54461, sessionid = 0x139791c2bfb0004, negotiated timeout = 8000
[junit4:junit4]   2> 5393 T2547 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@70729 name:ZooKeeperConnection Watcher:127.0.0.1:54461/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5393 T2501 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5393 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5395 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5397 T2501 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54476_solr
[junit4:junit4]   2> 5398 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:54476_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:54476_solr
[junit4:junit4]   2> 5398 T2501 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54476_solr
[junit4:junit4]   2> 5401 T2535 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5401 T2521 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5402 T2501 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5402 T2501 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5403 T2501 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5403 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5403 T2501 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5403 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5403 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5403 T2501 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5404 T2501 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5404 T2501 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5413 T2501 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5423 T2501 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1
[junit4:junit4]   2> 5423 T2501 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5424 T2501 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5424 T2501 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5425 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\'
[junit4:junit4]   2> 5427 T2501 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty3/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5428 T2501 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty3/collection1/lib/README' to classloader
[junit4:junit4]   2> 5469 T2501 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5528 T2501 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5529 T2501 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5538 T2501 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5539 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5547 T2547 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5547 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5547 T2535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6091 T2501 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6104 T2501 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6106 T2501 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6119 T2501 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6123 T2501 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6126 T2501 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6128 T2501 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6129 T2501 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\
[junit4:junit4]   2> 6129 T2501 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1813820
[junit4:junit4]   2> 6130 T2501 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index/
[junit4:junit4]   2> 6130 T2501 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6131 T2501 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index forceNew:false
[junit4:junit4]   2> 6137 T2501 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6137 T2501 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6138 T2501 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6139 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6139 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6140 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6140 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6140 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6140 T2501 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6141 T2501 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6141 T2501 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6141 T2501 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6142 T2501 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6142 T2501 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6142 T2501 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6144 T2501 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6144 T2501 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6144 T2501 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6144 T2501 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6144 T2501 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6145 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6145 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6145 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6147 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6147 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6148 T2501 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6148 T2501 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6149 T2501 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6151 T2501 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6154 T2501 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6156 T2501 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 6159 T2501 oass.SolrIndexSearcher.<init> Opening Searcher@11a0347 main
[junit4:junit4]   2> 6160 T2501 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6161 T2501 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6161 T2501 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6164 T2501 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6182 T2548 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11a0347 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6187 T2501 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6187 T2501 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:54476/solr shardId:shard4
[junit4:junit4]   2> 6188 T2501 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard4/election
[junit4:junit4]   2> 6196 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0004 type:delete cxid:0x41 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard4 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard4
[junit4:junit4]   2> 6198 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0004 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6200 T2501 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Running the leader process. afterExperiation=false
[junit4:junit4]   2> 6409 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6420 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6420 T2535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6420 T2547 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7056 T2501 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7056 T2501 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54476/solr/collection1/
[junit4:junit4]   2> 7485 T2501 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard4
[junit4:junit4]   2> 7489 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0004 type:create cxid:0x4b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7712 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7719 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7719 T2547 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7719 T2535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7753 T2501 oasc.ZkController.register We are http://127.0.0.1:54476/solr/collection1/ and leader is http://127.0.0.1:54476/solr/collection1/
[junit4:junit4]   2> 7753 T2501 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54476/solr
[junit4:junit4]   2> 7753 T2501 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 7755 T2501 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7756 T2501 oass.SolrDispatchFilter.init user.dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 7756 T2501 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7756 T2501 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7998 T2501 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 8001 T2501 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:54480
[junit4:junit4]   2> 8002 T2501 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8002 T2501 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4
[junit4:junit4]   2> 8002 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\'
[junit4:junit4]   2> 8031 T2501 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8032 T2501 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8032 T2501 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4
[junit4:junit4]   2> 8032 T2501 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\solr.xml
[junit4:junit4]   2> 8032 T2501 oasc.CoreContainer.<init> New CoreContainer 32167325
[junit4:junit4]   2> 8033 T2501 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\'
[junit4:junit4]   2> 8033 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\'
[junit4:junit4]   2> 8059 T2501 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8070 T2501 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54461/solr
[junit4:junit4]   2> 8071 T2501 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54461/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@8d3290
[junit4:junit4]   2> 8072 T2558 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54461
[junit4:junit4]   2> 8072 T2501 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8073 T2558 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:54461, initiating session
[junit4:junit4]   2> 8073 T2503 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54483
[junit4:junit4]   2> 8074 T2503 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54483
[junit4:junit4]   2> 8079 T2505 oazs.NIOServerCnxn.finishSessionInit Established session 0x139791c2bfb0005 with negotiated timeout 8000 for client /127.0.0.1:54483
[junit4:junit4]   2> 8079 T2558 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:54461, sessionid = 0x139791c2bfb0005, negotiated timeout = 8000
[junit4:junit4]   2> 8079 T2559 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8d3290 name:ZooKeeperConnection Watcher:127.0.0.1:54461/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8079 T2501 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8080 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8082 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8084 T2501 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54480_solr
[junit4:junit4]   2> 8084 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:54480_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:54480_solr
[junit4:junit4]   2> 8085 T2501 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54480_solr
[junit4:junit4]   2> 8088 T2547 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8088 T2521 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8088 T2535 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8089 T2501 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8089 T2501 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8089 T2501 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8089 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8090 T2501 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8090 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8090 T2501 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8091 T2501 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8091 T2501 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8096 T2501 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8106 T2501 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8112 T2501 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1
[junit4:junit4]   2> 8112 T2501 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8113 T2501 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8113 T2501 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8115 T2501 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\'
[junit4:junit4]   2> 8116 T2501 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty4/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8117 T2501 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty4/collection1/lib/README' to classloader
[junit4:junit4]   2> 8152 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8159 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8159 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8160 T2535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8160 T2547 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8160 T2501 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 8227 T2501 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 8228 T2501 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8232 T2501 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 8785 T2501 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 8793 T2501 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8796 T2501 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8808 T2501 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8812 T2501 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8816 T2501 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8816 T2501 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8818 T2501 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\
[junit4:junit4]   2> 8818 T2501 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1813820
[junit4:junit4]   2> 8819 T2501 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index/
[junit4:junit4]   2> 8820 T2501 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 8820 T2501 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index forceNew:false
[junit4:junit4]   2> 8826 T2501 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8826 T2501 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 8826 T2501 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 8827 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 8827 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 8828 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 8828 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 8829 T2501 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 8829 T2501 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 8829 T2501 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 8830 T2501 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 8831 T2501 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 8831 T2501 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 8831 T2501 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 8832 T2501 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 8832 T2501 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 8832 T2501 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 8832 T2501 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 8838 T2501 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 8838 T2501 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8839 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8839 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8840 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8840 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8841 T2501 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8841 T2501 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8841 T2501 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 8841 T2501 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 8845 T2501 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 8848 T2501 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 8850 T2501 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 8853 T2501 oass.SolrIndexSearcher.<init> Opening Searcher@320810 main
[junit4:junit4]   2> 8854 T2501 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 8855 T2501 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8855 T2501 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 8857 T2501 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 8872 T2560 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@320810 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8879 T2501 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 8880 T2501 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:54480/solr shardId:shard5
[junit4:junit4]   2> 8881 T2501 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard5/election
[junit4:junit4]   2> 8890 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0005 type:delete cxid:0x41 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard5 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard5
[junit4:junit4]   2> 8892 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0005 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8893 T2501 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Running the leader process. afterExperiation=false
[junit4:junit4]   2> 9022 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9033 T2535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9033 T2547 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9034 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9034 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9749 T2501 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 9749 T2501 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54480/solr/collection1/
[junit4:junit4]   2> 10177 T2501 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard5
[junit4:junit4]   2> 10183 T2506 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139791c2bfb0005 type:create cxid:0x4b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 10328 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10337 T2535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10338 T2547 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10338 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10339 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10362 T2501 oasc.ZkController.register We are http://127.0.0.1:54480/solr/collection1/ and leader is http://127.0.0.1:54480/solr/collection1/
[junit4:junit4]   2> 10362 T2501 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54480/solr
[junit4:junit4]   2> 10362 T2501 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 10365 T2501 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10367 T2501 oass.SolrDispatchFilter.init user.dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 10367 T2501 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 10368 T2501 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C168 name=collection1 org.apache.solr.core.SolrCore@cccd03 url=http://127.0.0.1:54468/solr/collection1 node=127.0.0.1:54468_solr
[junit4:junit4]   2> 10392 T2513 C168 P54468 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10393 T2513 C168 P54468 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10395 T2513 C168 P54468 oass.SolrIndexSearcher.<init> Opening Searcher@14b9d04 realtime
[junit4:junit4]   2> 10395 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1411757284709629952)} 0 10
[junit4:junit4]   2> ASYNC  NEW_CORE C169 name=collection1 org.apache.solr.core.SolrCore@3d73b7 url=http://127.0.0.1:54472/solr/collection1 node=127.0.0.1:54472_solr
[junit4:junit4]   2> 10415 T2528 C169 P54472 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10416 T2528 C169 P54472 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10418 T2528 C169 P54472 oass.SolrIndexSearcher.<init> Opening Searcher@24200b realtime
[junit4:junit4]   2> ASYNC  NEW_CORE C170 name=collection1 org.apache.solr.core.SolrCore@f34e71 url=http://127.0.0.1:54480/solr/collection1 node=127.0.0.1:54480_solr
[junit4:junit4]   2> 10430 T2551 C170 P54480 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10431 T2551 C170 P54480 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> ASYNC  NEW_CORE C171 name=collection1 org.apache.solr.core.SolrCore@1ecc90a url=http://127.0.0.1:54476/solr/collection1 node=127.0.0.1:54476_solr
[junit4:junit4]   2> 10431 T2540 C171 P54476 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10432 T2540 C171 P54476 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10432 T2551 C170 P54480 oass.SolrIndexSearcher.<init> Opening Searcher@7afe76 realtime
[junit4:junit4]   2> 10433 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 10435 T2540 C171 P54476 oass.SolrIndexSearcher.<init> Opening Searcher@188040a realtime
[junit4:junit4]   2> 10435 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 10436 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1411757284734795776)} 0 26
[junit4:junit4]   2> 10443 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10444 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 10445 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1411757284764155904)} 0 8
[junit4:junit4]   2> 10451 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10451 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10451 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1411757284772544512)} 0 5
[junit4:junit4]   2> 10457 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1411757284780933120)]} 0 3
[junit4:junit4]   2> 10461 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1411757284786176000)]} 0 2
[junit4:junit4]   2> 10466 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1411757284792467456)]} 0 1
[junit4:junit4]   2> 10472 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10473 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 5
[junit4:junit4]   2> 10477 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3 (1411757284805050368)]} 0 0
[junit4:junit4]   2> 10483 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10484 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 4
[junit4:junit4]   2> 10487 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4 (1411757284815536128)]} 0 0
[junit4:junit4]   2> 10505 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10506 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 4
[junit4:junit4]   2> 10508 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5 (1411757284836507648)]} 0 1
[junit4:junit4]   2> 10515 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10516 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 5
[junit4:junit4]   2> 10519 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6 (1411757284848041984)]} 0 1
[junit4:junit4]   2> 10525 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10526 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 4
[junit4:junit4]   2> 10529 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7 (1411757284858527744)]} 0 1
[junit4:junit4]   2> 10536 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10537 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 5
[junit4:junit4]   2> 10540 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8 (1411757284870062080)]} 0 1
[junit4:junit4]   2> 10544 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8 (1411757284874256384)]} 0 1
[junit4:junit4]   2> 10547 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9 (1411757284877402112)]} 0 1
[junit4:junit4]   2> 10551 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10553 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 4
[junit4:junit4]   2> 10576 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10 (1411757284886839296)]} 0 21
[junit4:junit4]   2> 10581 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10582 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 4
[junit4:junit4]   2> 10587 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11 (1411757284917248000)]} 0 3
[junit4:junit4]   2> 10591 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10593 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 5
[junit4:junit4]   2> 10595 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12 (1411757284927733760)]} 0 1
[junit4:junit4]   2> 10600 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10600 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 2
[junit4:junit4]   2> 10604 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13 (1411757284938219520)]} 0 0
[junit4:junit4]   2> 10610 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10611 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 4
[junit4:junit4]   2> 10615 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14 (1411757284948705280)]} 0 1
[junit4:junit4]   2> 10620 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10621 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 3
[junit4:junit4]   2> 10625 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15 (1411757284959191040)]} 0 1
[junit4:junit4]   2> 10630 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10631 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 4
[junit4:junit4]   2> 10635 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16 (1411757284969676800)]} 0 1
[junit4:junit4]   2> 10640 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10641 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 4
[junit4:junit4]   2> 10647 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17 (1411757284980162560)]} 0 3
[junit4:junit4]   2> 10654 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10655 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 7
[junit4:junit4]   2> 10659 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100 (1411757284994842624)]} 0 1
[junit4:junit4]   2> 10663 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10665 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 4
[junit4:junit4]   2> 10669 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101 (1411757285005328384)]} 0 1
[junit4:junit4]   2> 10675 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10676 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 5
[junit4:junit4]   2> 10700 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102 (1411757285016862720)]} 0 21
[junit4:junit4]   2> 10705 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10706 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 4
[junit4:junit4]   2> 10721 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103 (1411757285057757184)]} 0 11
[junit4:junit4]   2> 10728 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10728 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 3
[junit4:junit4]   2> 10732 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104 (1411757285071388672)]} 0 1
[junit4:junit4]   2> 10736 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104 (1411757285075582976)]} 0 1
[junit4:junit4]   2> 10739 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105 (1411757285079777280)]} 0 0
[junit4:junit4]   2> 10743 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105 (1411757285082923008)]} 0 1
[junit4:junit4]   2> 10747 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106 (1411757285087117312)]} 0 1
[junit4:junit4]   2> 10750 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106 (1411757285091311616)]} 0 0
[junit4:junit4]   2> 10754 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107 (1411757285094457344)]} 0 1
[junit4:junit4]   2> 10758 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107 (1411757285098651648)]} 0 1
[junit4:junit4]   2> 10762 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108 (1411757285102845952)]} 0 1
[junit4:junit4]   2> 10768 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10769 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108]} 0 5
[junit4:junit4]   2> 10773 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109 (1411757285114380288)]} 0 1
[junit4:junit4]   2> 10776 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109 (1411757285117526016)]} 0 1
[junit4:junit4]   2> 10780 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110 (1411757285121720320)]} 0 1
[junit4:junit4]   2> 10787 T2522 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10801 T2535 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10803 T2547 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10803 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10805 T2521 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10806 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=22 
[junit4:junit4]   2>  C170_STATE=coll:collection1 core:collection1 props:{shard=shard5, leader=true, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:54480_solr, base_url=http://127.0.0.1:54480/solr}
[junit4:junit4]   2> 10807 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 24
[junit4:junit4]   2> 10812 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1411757285155274752)]} 0 1
[junit4:junit4]   2> 10841 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=24 
[junit4:junit4]   2> 10841 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 26
[junit4:junit4]   2> 10870 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112 (1411757285189877760)]} 0 26
[junit4:junit4]   2> 10899 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112 (1411757285220286464)]} 0 26
[junit4:junit4]   2> 10904 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113 (1411757285250695168)]} 0 2
[junit4:junit4]   2> 10911 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 10912 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 5
[junit4:junit4]   2> 10916 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114 (1411757285264326656)]} 0 1
[junit4:junit4]   2> 10921 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10930 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 12
[junit4:junit4]   2> 10934 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115 (1411757285283201024)]} 0 1
[junit4:junit4]   2> 10940 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10940 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115]} 0 3
[junit4:junit4]   2> 10943 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116 (1411757285293686784)]} 0 0
[junit4:junit4]   2> 10947 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10949 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116]} 0 4
[junit4:junit4]   2> 10953 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117 (1411757285303123968)]} 0 1
[junit4:junit4]   2> 10956 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117 (1411757285306269696)]} 0 1
[junit4:junit4]   2> 10959 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118 (1411757285309415424)]} 0 1
[junit4:junit4]   2> 10966 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 10966 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 5
[junit4:junit4]   2> 10970 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119 (1411757285320949760)]} 0 1
[junit4:junit4]   2> 10978 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10980 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 6
[junit4:junit4]   2> 10984 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120 (1411757285335629824)]} 0 1
[junit4:junit4]   2> 10988 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120 (1411757285339824128)]} 0 1
[junit4:junit4]   2> 10991 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121 (1411757285342969856)]} 0 1
[junit4:junit4]   2> 10998 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10999 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 5
[junit4:junit4]   2> 11024 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122 (1411757285354504192)]} 0 23
[junit4:junit4]   2> 11028 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122 (1411757285381767168)]} 0 1
[junit4:junit4]   2> 11034 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1411757285385961472)]} 0 3
[junit4:junit4]   2> 11039 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11040 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123]} 0 5
[junit4:junit4]   2> 11044 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124 (1411757285398544384)]} 0 1
[junit4:junit4]   2> 11048 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11049 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 3
[junit4:junit4]   2> 11053 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125 (1411757285407981568)]} 0 1
[junit4:junit4]   2> 11057 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11058 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 3
[junit4:junit4]   2> 11061 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126 (1411757285416370176)]} 0 1
[junit4:junit4]   2> 11066 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11066 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 3
[junit4:junit4]   2> 11069 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127 (1411757285424758784)]} 0 1
[junit4:junit4]   2> 11072 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127 (1411757285427904512)]} 0 1
[junit4:junit4]   2> 11075 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128 (1411757285431050240)]} 0 1
[junit4:junit4]   2> 11078 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128 (1411757285434195968)]} 0 1
[junit4:junit4]   2> 11080 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129 (1411757285437341696)]} 0 0
[junit4:junit4]   2> 11085 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11085 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 3
[junit4:junit4]   2> 11089 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130 (1411757285445730304)]} 0 1
[junit4:junit4]   2> 11092 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11093 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130]} 0 2
[junit4:junit4]   2> 11096 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131 (1411757285453070336)]} 0 1
[junit4:junit4]   2> 11100 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11101 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131]} 0 3
[junit4:junit4]   2> 11121 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132 (1411757285461458944)]} 0 18
[junit4:junit4]   2> 11125 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132 (1411757285483479040)]} 0 1
[junit4:junit4]   2> 11128 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133 (1411757285485576192)]} 0 2
[junit4:junit4]   2> 11132 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11133 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133]} 0 3
[junit4:junit4]   2> 11136 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134 (1411757285495013376)]} 0 1
[junit4:junit4]   2> 11149 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11152 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 14
[junit4:junit4]   2> 11157 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135 (1411757285517033472)]} 0 1
[junit4:junit4]   2> 11200 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135 (1411757285532762112)]} 0 29
[junit4:junit4]   2> 11204 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136 (1411757285567365120)]} 0 0
[junit4:junit4]   2> 11225 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11225 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 3
[junit4:junit4]   2> 11228 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137 (1411757285591482368)]} 0 1
[junit4:junit4]   2> 11233 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11234 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137]} 0 4
[junit4:junit4]   2> 11237 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138 (1411757285600919552)]} 0 1
[junit4:junit4]   2> 11242 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11243 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138]} 0 4
[junit4:junit4]   2> 11247 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139 (1411757285611405312)]} 0 1
[junit4:junit4]   2> 11250 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139 (1411757285615599616)]} 0 0
[junit4:junit4]   2> 11254 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140 (1411757285619793920)]} 0 0
[junit4:junit4]   2> 11260 T2540 C171 P54476 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11260 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 3
[junit4:junit4]   2> 11264 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141 (1411757285629231104)]} 0 1
[junit4:junit4]   2> 11268 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141 (1411757285633425408)]} 0 1
[junit4:junit4]   2> 11292 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142 (1411757285637619712)]} 0 21
[junit4:junit4]   2> 11296 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142 (1411757285662785536)]} 0 1
[junit4:junit4]   2> 11301 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143 (1411757285665931264)]} 0 3
[junit4:junit4]   2> 11325 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143 (1411757285671174144)]} 0 22
[junit4:junit4]   2> 11329 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144 (1411757285697388544)]} 0 1
[junit4:junit4]   2> 11336 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 11337 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 6
[junit4:junit4]   2> 11341 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145 (1411757285709971456)]} 0 1
[junit4:junit4]   2> 11346 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54476/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11347 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 4
[junit4:junit4]   2> 11351 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146 (1411757285720457216)]} 0 1
[junit4:junit4]   2> 11356 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11358 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 5
[junit4:junit4]   2> 11362 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147 (1411757285731991552)]} 0 1
[junit4:junit4]   2> 11367 T2551 C170 P54480 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54472/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11368 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 4
[junit4:junit4]   2> 11372 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148 (1411757285742477312)]} 0 1
[junit4:junit4]   2> 11375 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148 (1411757285746671616)]} 0 0
[junit4:junit4]   2> 11379 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149 (1411757285749817344)]} 0 1
[junit4:junit4]   2> 11385 T2527 C169 P54472 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:54480/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11386 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149]} 0 4
[junit4:junit4]   2> 11387 T2513 C168 P54468 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11644 T2513 C168 P54468 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index,segFN=segments_2,generation=2,filenames=[_5_nrm.cfe, _1_NestedPulsing_0.tip, _2_Direct_0.tip, _2_NestedPulsing_0.tim, _5_NestedPulsing_0.tip, _2_Block_0.pos, _2_Direct_0.tim, _5_NestedPulsing_0.tim, _3_MockRandom_0.prx, _6_Direct_0.tip, _6_Direct_0.tim, _3.fdt, _3_NestedPulsing_0.prx, _6_Block_0.pos, _6_NestedPulsing_0.prx, _5_MockRandom_0.pyl, _1_NestedPulsing_0.tim, _2_NestedPulsing_0.tip, _5_MockRandom_0.pos, _6_MockRandom_0.skp, _1_Block_0.pos, _1_Block_0.tip, _5_Direct_0.prx, _1_Block_0.tim, _1_MockRandom_0.tip, _2.fdt, _3_NestedPulsing_0.tim, _1_MockRandom_0.tim, _1_MockRandom_0.sd, _2.fdx, _0_MockRandom_0.skp, _3_Block_0.pos, _3_Block_0.doc, _1.fdx, _0_MockRandom_0.frq, _4_MockRandom_0.prx, _5_nrm.cfs, _1.fdt, _3_NestedPulsing_0.tip, _4_MockRandom_0.sd, _3.si, _4_NestedPulsing_0.frq, _6.fdt, _2.si, _6_NestedPulsing_0.frq, _5_Direct_0.tim, _6_MockRandom_0.tip, _2_Block_0.tim, _6.fdx, _6_MockRandom_0.tim, _2_Block_0.tip, _3_MockRandom_0.frq, _3.fnm, _0_NestedPulsing_0.tip, _5_Block_0.tim, _2_MockRandom_0.tiv, _0_NestedPulsing_0.tim, _2_nrm.cfe, _6_MockRandom_0.pyl, _2_MockRandom_0.pyl, _4_NestedPulsing_0.tip, _6_MockRandom_0.doc, _4_NestedPulsing_0.tim, _2_MockRandom_0.tib, _2_nrm.cfs, _5_Direct_0.tip, _5_MockRandom_0.doc, _5_Block_0.doc, _4_Block_0.pos, _3_Block_0.tip, _4_Block_0.tip, _2_NestedPulsing_0.prx, _0_NestedPulsing_0.frq, _5_MockRandom_0.skp, _1_Direct_0.prx, _0_NestedPulsing_0.prx, _6_Direct_0.prx, _2.fnm, _3.fdx, _2_MockRandom_0.sd, _1_NestedPulsing_0.frq, _5_NestedPulsing_0.frq, _2_MockRandom_0.skp, _1.si, _2_NestedPulsing_0.frq, _6_NestedPulsing_0.tip, _6.si, _6.fnm, _0_Direct_0.tip, _0_Direct_0.prx, _0_Direct_0.tim, _2_MockRandom_0.doc, _6_NestedPulsing_0.tim, _1_Direct_0.frq, _6_MockRandom_0.frq, _3_nrm.cfe, _0_Block_0.tip, _3_Direct_0.frq, _5.fdt, _0_Block_0.tim, _4_nrm.cfe, _4.fnm, _1.fnm, _4.fdx, _3_Block_0.tim, _4_Direct_0.frq, _5_MockRandom_0.sd, _4_MockRandom_0.tip, _4_NestedPulsing_0.prx, _3_Direct_0.prx, _1_Direct_0.tim, _1_Block_0.doc, _4_MockRandom_0.tim, _1_Direct_0.tip, _3_Direct_0.tip, _4_Block_0.tim, _0.fnm, _6_Block_0.doc, _4_Direct_0.tim, _5_Direct_0.frq, _0_Direct_0.frq, _5.si, _3_nrm.cfs, _6_MockRandom_0.sd, _3_MockRandom_0.tip, _3_NestedPulsing_0.frq, _4_Direct_0.prx, _0_MockRandom_0.doc, _3_MockRandom_0.tim, _4_nrm.cfs, _0_MockRandom_0.pos, _4_Direct_0.tip, _1_MockRandom_0.frq, _5_Block_0.tip, _3_Direct_0.tim, _5_MockRandom_0.frq, _0_Block_0.pos, _5_MockRandom_0.tib, _1_NestedPulsing_0.prx, _6_MockRandom_0.pos, _4_Block_0.doc, _6_nrm.cfs, _5_Block_0.pos, _2_MockRandom_0.pos, _0.si, _5_MockRandom_0.tiv, _1_nrm.cfs, _5.fnm, _0_Block_0.doc, _6_nrm.cfe, _4.si, _4.fdt, _0_nrm.cfs, _1_nrm.cfe, _3_MockRandom_0.sd, _2_Direct_0.prx, _5_NestedPulsing_0.prx, _1_MockRandom_0.prx, _0_MockRandom_0.tip, _0_MockRandom_0.sd, _4_MockRandom_0.frq, _6_Block_0.tip, _0_nrm.cfe, _5.fdx, segments_2, _2_MockRandom_0.frq, _0.fdx, _2_Direct_0.frq, _6_Block_0.tim, _0_MockRandom_0.pyl, _2_Block_0.doc, _6_Direct_0.frq, _0_MockRandom_0.tim, _0.fdt]
[junit4:junit4]   2> 11645 T2513 C168 P54468 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 11653 T2513 C168 P54468 oass.SolrIndexSearcher.<init> Opening Searcher@576215 main
[junit4:junit4]   2> 11655 T2513 C168 P54468 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11656 T2524 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@576215 main{StandardDirectoryReader(segments_2:15:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10 _3(5.0):C10 _4(5.0):C10 _5(5.0):C10 _6(5.0):C7)}
[junit4:junit4]   2> 11657 T2513 C168 P54468 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 270
[junit4:junit4]   2> 11659 T2528 C169 P54472 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11780 T2528 C169 P54472 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index,segFN=segments_2,generation=2,filenames=[_2_NestedPulsing_0.tim, _2_Direct_0.tip, _1_NestedPulsing_0.tip, _2_Block_0.pos, _0_Block_0.tip, _2_Direct_0.tim, _0_Block_0.tim, _1.fnm, _1_MockRandom_0.pos, _1_Direct_0.tim, _1_NestedPulsing_0.tim, _1_Block_0.doc, _2_NestedPulsing_0.tip, _1_Direct_0.tip, _1_Block_0.pos, _1_Block_0.tip, _1_MockRandom_0.tiv, _0.fnm, _1_Block_0.tim, _2.fdt, _1_MockRandom_0.sd, _0_Direct_0.frq, _2.fdx, _0_MockRandom_0.skp, _1.fdx, _0_MockRandom_0.frq, _1_MockRandom_0.tib, _1.fdt, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _1_MockRandom_0.doc, _1_MockRandom_0.frq, _2.si, _0_Block_0.pos, _2_MockRandom_0.tii, _2_Block_0.tim, _1_NestedPulsing_0.prx, _2_Block_0.tip, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _2_nrm.cfe, _2_MockRandom_0.tib, _2_nrm.cfs, _0.si, _2_MockRandom_0.prx, _0_MockRandom_0.tib, _1_nrm.cfs, _0_Block_0.doc, _2_NestedPulsing_0.prx, _0_NestedPulsing_0.frq, _0_nrm.cfs, _1_MockRandom_0.skp, _1_Direct_0.prx, _0_NestedPulsing_0.prx, _2.fnm, _1_nrm.cfe, _2_MockRandom_0.sd, _2_Direct_0.prx, _0_MockRandom_0.sd, _0_MockRandom_0.tiv, _1_MockRandom_0.pyl, _1_NestedPulsing_0.frq, _1.si, _2_NestedPulsing_0.frq, _0_nrm.cfe, _2_MockRandom_0.frq, segments_2, _2_Direct_0.frq, _0_Direct_0.tip, _0.fdx, _0_Direct_0.prx, _2_Block_0.doc, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq]
[junit4:junit4]   2> 11781 T2528 C169 P54472 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 11789 T2528 C169 P54472 oass.SolrIndexSearcher.<init> Opening Searcher@1a79c61 main
[junit4:junit4]   2> 11790 T2528 C169 P54472 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11791 T2536 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a79c61 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C5)}
[junit4:junit4]   2> 11792 T2528 C169 P54472 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:54476/solr/collection1/, StdNode: http://127.0.0.1:54480/solr/collection1/]
[junit4:junit4]   2> 11794 T2551 C170 P54480 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11794 T2540 C171 P54476 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11922 T2540 C171 P54476 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index,segFN=segments_2,generation=2,filenames=[_1_NestedPulsing_0.tip, _0_Block_0.tip, _0_Block_0.pos, _1_NestedPulsing_0.prx, _0_Block_0.tim, _0_NestedPulsing_0.tip, _1.fnm, _0_NestedPulsing_0.tim, _1_Direct_0.tim, _1_NestedPulsing_0.tim, _0.si, _1_Block_0.doc, _1_Direct_0.tip, _1_Block_0.tip, _1_Block_0.pos, _1_nrm.cfs, _0_MockRandom_0.tib, _0_Block_0.doc, _0.fnm, _1_Block_0.tim, _0_NestedPulsing_0.frq, _1_MockRandom_0.sd, _0_nrm.cfs, _0_Direct_0.frq, _1_Direct_0.prx, _1_MockRandom_0.tii, _0_NestedPulsing_0.prx, _0_MockRandom_0.skp, _1_nrm.cfe, _1.fdx, _1_MockRandom_0.prx, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _1_NestedPulsing_0.frq, _1_MockRandom_0.tib, _0_MockRandom_0.tiv, _1.fdt, _0_MockRandom_0.doc, _1.si, _0_MockRandom_0.pos, _0_nrm.cfe, segments_2, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _1_MockRandom_0.frq, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq]
[junit4:junit4]   2> 11922 T2540 C171 P54476 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 11933 T2540 C171 P54476 oass.SolrIndexSearcher.<init> Opening Searcher@17787e main
[junit4:junit4]   2> 11935 T2540 C171 P54476 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11946 T2548 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17787e main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 11948 T2540 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 154
[junit4:junit4]   2> 11958 T2551 C170 P54480 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index,segFN=segments_2,generation=2,filenames=[_2_NestedPulsing_0.tim, _1_NestedPulsing_0.tip, _2_Direct_0.tip, _2_Block_0.pos, _0_Block_0.tip, _2_Direct_0.tim, _0_Block_0.tim, _0_MockRandom_0.prx, _1.fnm, _1_Direct_0.tim, _1_NestedPulsing_0.tim, _1_Block_0.doc, _2_NestedPulsing_0.tip, _1_Direct_0.tip, _1_Block_0.pos, _1_Block_0.tip, _0.fnm, _1_Block_0.tim, _1_MockRandom_0.tip, _2.fdt, _1_MockRandom_0.sd, _1_MockRandom_0.tim, _0_Direct_0.frq, _2.fdx, _1.fdx, _0_MockRandom_0.frq, _1.fdt, _1_MockRandom_0.frq, _2.si, _0_Block_0.pos, _2_MockRandom_0.tii, _2_Block_0.tim, _1_NestedPulsing_0.prx, _2_Block_0.tip, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _2_nrm.cfe, _2_MockRandom_0.tib, _2_nrm.cfs, _0.si, _2_MockRandom_0.prx, _1_nrm.cfs, _0_Block_0.doc, _2_NestedPulsing_0.prx, _0_NestedPulsing_0.frq, _0_nrm.cfs, _1_Direct_0.prx, _0_NestedPulsing_0.prx, _2.fnm, _1_nrm.cfe, _2_MockRandom_0.sd, _2_Direct_0.prx, _0_MockRandom_0.tip, _0_MockRandom_0.sd, _1_MockRandom_0.prx, _1_NestedPulsing_0.frq, _1.si, _2_NestedPulsing_0.frq, _0_nrm.cfe, _2_MockRandom_0.frq, segments_2, _2_Direct_0.frq, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _2_Block_0.doc, _0_MockRandom_0.tim, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq]
[junit4:junit4]   2> 11958 T2551 C170 P54480 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 11967 T2551 C170 P54480 oass.SolrIndexSearcher.<init> Opening Searcher@8a900d main
[junit4:junit4]   2> 11968 T2551 C170 P54480 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11969 T2560 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8a900d main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C3)}
[junit4:junit4]   2> 11970 T2551 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 176
[junit4:junit4]   2> 11970 T2528 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 311
[junit4:junit4]   2> 11973 T2542 C171 P54476 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11976 T2542 C171 P54476 oass.SolrIndexSearcher.<init> Opening Searcher@11ca6db main
[junit4:junit4]   2> 11976 T2542 C171 P54476 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11977 T2548 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11ca6db main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 11978 T2542 C171 P54476 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:54472/solr/collection1/, StdNode: http://127.0.0.1:54480/solr/collection1/]
[junit4:junit4]   2> 11980 T2551 C170 P54480 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11980 T2527 C169 P54472 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11982 T2551 C170 P54480 oass.SolrIndexSearcher.<init> Opening Searcher@748ad5 main
[junit4:junit4]   2> 11983 T2551 C170 P54480 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11984 T2560 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@748ad5 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C3)}
[junit4:junit4]   2> 11984 T2527 C169 P54472 oass.SolrIndexSearcher.<init> Opening Searcher@1845cf3 main
[junit4:junit4]   2> 11984 T2551 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 4
[junit4:junit4]   2> 11985 T2527 C169 P54472 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11987 T2536 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1845cf3 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C5)}
[junit4:junit4]   2> 11987 T2527 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 7
[junit4:junit4]   2> 11989 T2542 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 16
[junit4:junit4]   2> 11990 T2552 C170 P54480 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11992 T2552 C170 P54480 oass.SolrIndexSearcher.<init> Opening Searcher@12b8d7d main
[junit4:junit4]   2> 11992 T2552 C170 P54480 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11994 T2560 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12b8d7d main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C3)}
[junit4:junit4]   2> 11994 T2552 C170 P54480 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:54472/solr/collection1/, StdNode: http://127.0.0.1:54476/solr/collection1/]
[junit4:junit4]   2> 11997 T2527 C169 P54472 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11997 T2540 C171 P54476 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11998 T2540 C171 P54476 oass.SolrIndexSearcher.<init> Opening Searcher@177c9bb main
[junit4:junit4]   2> 11998 T2527 C169 P54472 oass.SolrIndexSearcher.<init> Opening Searcher@c6bff7 main
[junit4:junit4]   2> 11999 T2540 C171 P54476 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11999 T2527 C169 P54472 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12000 T2536 oasc.JmxMonitoredMap.put WARNING Failed to register info bean: searcher javax.management.InstanceAlreadyExistsException: solr/collection1:type=searcher,id=org.apache.solr.search.SolrIndexSearcher
[junit4:junit4]   2> 	at com.sun.jmx.mbeanserver.Repository.addMBean(Repository.java:437)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerWithRepository(DefaultMBeanServerInterceptor.java:1898)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerDynamicMBean(DefaultMBeanServerInterceptor.java:966)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerObject(DefaultMBeanServerInterceptor.java:900)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerMBean(DefaultMBeanServerInterceptor.java:324)
[junit4:junit4]   2> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.registerMBean(JmxMBeanServer.java:512)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.put(JmxMonitoredMap.java:140)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.put(JmxMonitoredMap.java:51)
[junit4:junit4]   2> 	at org.apache.solr.search.SolrIndexSearcher.register(SolrIndexSearcher.java:242)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore.registerSearcher(SolrCore.java:1633)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore.access$000(SolrCore.java:121)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore$7.call(SolrCore.java:1510)
[junit4:junit4]   2> 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
[junit4:junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
[junit4:junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[junit4:junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[junit4:junit4]   2> 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 
[junit4:junit4]   2> 12000 T2548 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@177c9bb main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 12000 T2540 C171 P54476 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 3
[junit4:junit4]   2> 12001 T2536 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c6bff7 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C5)}
[junit4:junit4]   2> 12002 T2527 C169 P54472 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 5
[junit4:junit4]   2> 12003 T2552 C170 P54480 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 13
[junit4:junit4]   2> 12007 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_ti1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 12014 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665051&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=1 
[junit4:junit4]   2> 12014 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665051&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12016 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665051&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 12019 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665051&q=*:*&ids=138,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12019 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665051&q=*:*&ids=126,147,122,104,14&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12020 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665051&q=*:*&ids=1,101,119&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12021 T2542 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&wt=javabin&q=*:*&version=2} status=0 QTime=11 
[junit4:junit4]   2> 12023 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_ti1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12024 T2501 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 12033 T2501 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54461/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1cbf9fe
[junit4:junit4]   2> 12034 T2566 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54461
[junit4:junit4]   2> 12034 T2501 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12035 T2566 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:54461, initiating session
[junit4:junit4]   2> 12036 T2503 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:54496
[junit4:junit4]   2> 12036 T2503 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:54496
[junit4:junit4]   2> 12038 T2505 oazs.NIOServerCnxn.finishSessionInit Established session 0x139791c2bfb0006 with negotiated timeout 10000 for client /127.0.0.1:54496
[junit4:junit4]   2> 12039 T2566 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:54461, sessionid = 0x139791c2bfb0006, negotiated timeout = 10000
[junit4:junit4]   2> 12040 T2567 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1cbf9fe name:ZooKeeperConnection Watcher:127.0.0.1:54461/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12040 T2501 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12041 T2501 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 12052 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665090&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12053 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665090&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12052 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665090&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12057 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665090&q=*:*&ids=2,100,118&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12057 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665090&q=*:*&ids=114,16,142&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12057 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665090&q=*:*&ids=115,149,136,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12058 T2538 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 12062 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_f1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12069 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665106&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12069 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665106&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12092 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665106&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=1 
[junit4:junit4]   2> 12095 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665106&q=*:*&ids=114,106,140,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12096 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665106&q=*:*&ids=115&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12095 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665106&q=*:*&ids=143,10,145,123,104&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12097 T2528 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&wt=javabin&q=*:*&version=2} status=0 QTime=32 
[junit4:junit4]   2> 12100 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_f1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12105 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1346356665143&shard.url=127.0.0.1:54476/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12105 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1346356665143&shard.url=127.0.0.1:54480/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12106 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1346356665143&shard.url=127.0.0.1:54472/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12109 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665143&q=*:*&ids=138&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12110 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665143&q=*:*&ids=128,133,147,122,107,4&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 12110 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665143&q=*:*&ids=1,5,101&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12111 T2531 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&shards=shard3,shard4,shard5&q=*:*&collection=collection1&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 12114 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tf1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12119 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665158&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12120 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665158&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 12121 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665158&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=1 
[junit4:junit4]   2> 12124 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665158&q=*:*&ids=117,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12124 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665158&q=*:*&ids=2,121,123,129&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12125 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665158&q=*:*&ids=3,127,109,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12125 T2528 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=8 
[junit4:junit4]   2> 12127 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tf1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12135 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665171&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=1 
[junit4:junit4]   2> 12135 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665171&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12136 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665171&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12138 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665171&q=*:*&ids=126,122,118&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12139 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665171&q=*:*&ids=114,139,15&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12138 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665171&q=*:*&ids=6,149,9,141&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12141 T2552 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=11 
[junit4:junit4]   2> 12145 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_d1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12149 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665189&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12149 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665189&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12150 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665189&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 12155 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665189&q=*:*&ids=138,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12155 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665189&q=*:*&ids=125,115,136,149,103,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12156 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665189&q=*:*&ids=10,147&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12157 T2552 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 12159 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_d1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12164 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665203&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12164 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665203&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12166 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665203&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12169 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665203&q=*:*&ids=110,124,137&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12170 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665203&q=*:*&ids=2,122,130&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12169 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665203&q=*:*&ids=112,109,16,131&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12170 T2552 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=8 
[junit4:junit4]   2> 12175 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_td1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12182 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665220&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12182 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665220&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12183 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665220&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12187 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665220&q=*:*&ids=138,15,102,131&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12188 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665220&q=*:*&ids=111,4&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 12187 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665220&q=*:*&ids=134,5,105,141&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12190 T2528 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=11 
[junit4:junit4]   2> 12193 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_td1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12197 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665236&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12197 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665236&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12198 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665236&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12201 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665236&q=*:*&ids=122,14&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12201 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665236&q=*:*&ids=109,16&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12202 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665236&q=*:*&ids=113,110,149,124,120,119&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12203 T2552 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=8 
[junit4:junit4]   2> 12206 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_l1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12220 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665250&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=9 
[junit4:junit4]   2> 12220 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665250&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=9 
[junit4:junit4]   2> 12222 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665250&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=1 
[junit4:junit4]   2> 12225 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665250&q=*:*&ids=115,124&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12226 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665250&q=*:*&ids=116,15&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12227 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665250&q=*:*&ids=144,126,145,10,147,107&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 12227 T2531 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&wt=javabin&q=*:*&version=2} status=0 QTime=18 
[junit4:junit4]   2> 12230 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_l1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12236 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665274&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12237 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665274&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=1 
[junit4:junit4]   2> 12236 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665274&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12239 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665274&q=*:*&ids=100&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12239 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665274&q=*:*&ids=5,136,105,101,119&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12240 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665274&q=*:*&ids=3,127,138,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12242 T2552 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=8 
[junit4:junit4]   2> 12246 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12250 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665290&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12250 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665290&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12251 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665290&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12254 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665290&q=*:*&ids=135,117,141&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12254 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665290&q=*:*&ids=126,130,11,118&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12255 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665290&q=*:*&ids=138,109,8&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12257 T2542 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&wt=javabin&q=*:*&version=2} status=0 QTime=8 
[junit4:junit4]   2> 12259 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tl1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12263 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665302&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12263 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665302&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12263 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665302&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12267 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665302&q=*:*&ids=146,5,149,124&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12268 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665302&q=*:*&ids=128,121&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12267 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665302&q=*:*&ids=114,112,140,142&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12269 T2538 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=8 
[junit4:junit4]   2> 12273 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_dt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12277 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665316&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12278 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665316&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 12278 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665316&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=1 
[junit4:junit4]   2> 12282 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665316&q=*:*&ids=3,114,106&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12282 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665316&q=*:*&ids=6,110,149,103,119&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12283 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665316&q=*:*&ids=145,123&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12284 T2542 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 12285 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_dt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12291 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665329&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12291 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665329&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12291 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665329&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12295 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665329&q=*:*&ids=2,108&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12295 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665329&q=*:*&ids=1,115,137,17,141&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12295 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665329&q=*:*&ids=112,148,140&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12296 T2552 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=8 
[junit4:junit4]   2> 12300 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tdt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 12305 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665344&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12305 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665344&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12307 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665344&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 12311 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665344&q=*:*&ids=132,17&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12312 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665344&q=*:*&ids=145,147&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12311 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665344&q=*:*&ids=116,127,114,106,16,142&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12313 T2542 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&wt=javabin&q=*:*&version=2} status=0 QTime=10 
[junit4:junit4]   2> 12316 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tdt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12320 T2544 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1346356665359&shard.url=127.0.0.1:54476/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12320 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1346356665359&shard.url=127.0.0.1:54472/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12320 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1346356665359&shard.url=127.0.0.1:54480/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12325 T2529 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665359&q=*:*&ids=1,115,113,137,124,105&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12327 T2553 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665359&q=*:*&ids=126,144,122,111&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12328 T2538 C171 P54476 REQ [collection1] webapp=/solr path=/select params={collection=collection1&sort=n_tdt1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=10 
[junit4:junit4]   2> 12330 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=a_si+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12343 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665375&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=6 
[junit4:junit4]   2> 12344 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665375&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=1 
[junit4:junit4]   2> 12345 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665375&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=8 
[junit4:junit4]   2> 12348 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665375&q=*:*&ids=3,7,8,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12348 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665375&q=*:*&ids=1,5,9,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12350 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665375&q=*:*&ids=2,10&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12351 T2556 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=17 
[junit4:junit4]   2> 12353 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=a_si+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12358 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=a_si+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665397&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12358 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=a_si+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665397&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12360 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=a_si+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665397&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=1 
[junit4:junit4]   2> 12363 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665397&q=*:*&ids=3,7,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12364 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665397&q=*:*&ids=1,6,9,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12363 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665397&q=*:*&ids=2,4,11&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12365 T2528 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=a_si+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 12368 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&fl=*,score&q=*:*&distrib=false&wt=javabin&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12373 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665412&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12373 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665412&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12374 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665412&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12377 T2543 C171 P54476 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665412&q=*:*&ids=3,7,8,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12378 T2555 C170 P54480 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665412&q=*:*&ids=2,10&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12377 T2532 C169 P54472 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665412&q=*:*&ids=1,5,9,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12379 T2552 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&fl=*,score&wt=javabin&q=*:*&version=2} status=0 QTime=8 
[junit4:junit4]   2> 12382 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&fl=*,score&q=*:*&distrib=false&wt=javabin&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12386 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665426&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12386 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665426&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12386 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665426&start=0&q=*:*&isShard=true&fsv=true} hits=25 status=0 QTime=0 
[junit4:junit4]   2> 12391 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665426&q=*:*&ids=114,112,140,142&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12392 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:54472/solr/collection1/&NOW=1346356665426&q=*:*&ids=146,5,149,124&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 12393 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665426&q=*:*&ids=128,121&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 12394 T2528 C169 P54472 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&fl=*,score&shards=shard3,shard4,shard5&q=*:*&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 12396 T2513 C168 P54468 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 12400 T2554 C170 P54480 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54480/solr/collection1/&NOW=1346356665439&start=0&q=*:*&isShard=true&fsv=true} hits=23 status=0 QTime=0 
[junit4:junit4]   2> 12401 T2545 C171 P54476 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:54476/solr/collection1/&NOW=1346356665439&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 12400 T2530 C169 P54472 REQ [collection1] webapp=/solr path=/

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

g.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> 	at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1249)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:278)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:275)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:275)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:91)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:57)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:132)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> 
[junit4:junit4]   2> 111816 T2547 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 111816 T2547 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 111816 T2547 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 230071 T2501 oas.SolrTestCaseJ4.endTrackingSearchers SEVERE ERROR: SolrIndexSearcher opens=540 closes=539
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {n_f1=PostingsFormat(name=NestedPulsing), foo_b=PostingsFormat(name=Direct), cat=PostingsFormat(name=MockRandom), foo_d=Block(blocksize=128), foo_f=PostingsFormat(name=Direct), n_tl1=PostingsFormat(name=NestedPulsing), n_d1=PostingsFormat(name=MockRandom), rnd_b=Block(blocksize=128), intDefault=PostingsFormat(name=MockRandom), n_td1=PostingsFormat(name=NestedPulsing), timestamp=PostingsFormat(name=MockRandom), id=Block(blocksize=128), range_facet_sl=PostingsFormat(name=Direct), range_facet_si=PostingsFormat(name=NestedPulsing), oddField_s=PostingsFormat(name=Direct), sequence_i=PostingsFormat(name=Direct), name=Block(blocksize=128), foo_i=PostingsFormat(name=NestedPulsing), regex_dup_B_s=PostingsFormat(name=MockRandom), multiDefault=PostingsFormat(name=NestedPulsing), n_tf1=PostingsFormat(name=MockRandom), n_dt1=PostingsFormat(name=NestedPulsing), genre_s=PostingsFormat(name=Direct), author_t=PostingsFormat(name=NestedPulsing), n_ti1=Block(blocksize=128), range_facet_l=PostingsFormat(name=MockRandom), text=PostingsFormat(name=Direct), _version_=PostingsFormat(name=MockRandom), val_i=Block(blocksize=128), SubjectTerms_mfacet=PostingsFormat(name=MockRandom), series_t=PostingsFormat(name=NestedPulsing), a_t=PostingsFormat(name=MockRandom), n_tdt1=PostingsFormat(name=MockRandom), regex_dup_A_s=PostingsFormat(name=Direct), price=PostingsFormat(name=Direct), other_tl1=PostingsFormat(name=MockRandom), n_l1=PostingsFormat(name=MockRandom), a_si=PostingsFormat(name=NestedPulsing), inStock=PostingsFormat(name=Direct)}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=nl, timezone=America/Juneau
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_06 (32-bit)/cpus=2,threads=1,free=43241088,total=169500672
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestIBSimilarityFactory, FullSolrCloudDistribCmdsTest, TestStressLucene, TestConfig, TestBinaryResponseWriter, SpellCheckComponentTest, MultiTermTest, TestValueSourceCache, TestStressVersions, TestBinaryField, SystemInfoHandlerTest, SolrInfoMBeanTest, HighlighterConfigTest, AlternateDirectoryTest, BadIndexSchemaTest, SignatureUpdateProcessorFactoryTest, TestXIncludeConfig, TestLFUCache, FileBasedSpellCheckerTest, OutputWriterTest, ZkNodePropsTest, RequestHandlersTest, UniqFieldsUpdateProcessorFactoryTest, DefaultValueUpdateProcessorTest, TestDistributedGrouping, TestRealTimeGet, DateMathParserTest, TestGroupingSearch, LukeRequestHandlerTest, EchoParamsTest, DocumentAnalysisRequestHandlerTest, PreAnalyzedFieldTest, AutoCommitTest, MinimalSchemaTest, TestSolrCoreProperties, TestReplicationHandler, ReturnFieldsTest, StandardRequestHandlerTest, TestFaceting, PrimitiveFieldTypeTest, TestSolrXMLSerializer, SearchHandlerTest, BasicZkTest, PluginInfoTest, DistributedTermsComponentTest, TestMergePolicyConfig, PeerSyncTest, DistanceFunctionTest, FastVectorHighlighterTest, JsonLoaderTest, TestFastWriter, URLClassifyProcessorTest, TermVectorComponentTest, TestFoldingMultitermQuery, PingRequestHandlerTest, DistributedQueryElevationComponentTest, DateFieldTest, UpdateRequestProcessorFactoryTest, NumericFieldsTest, SampleTest, TestCoreContainer, TestDefaultSimilarityFactory, TestRecovery, TestQuerySenderNoQuery, SuggesterTest, TermVectorComponentDistributedTest, RecoveryZkTest, PathHierarchyTokenizerFactoryTest, DirectSolrSpellCheckerTest, TestSolrQueryParser, QueryElevationComponentTest, XmlUpdateRequestHandlerTest, DirectSolrConnectionTest, OverseerTest, LegacyHTMLStripCharFilterTest, DirectUpdateHandlerOptimizeTest, TestBadConfig, TestPHPSerializedResponseWriter, CoreAdminHandlerTest, ZkSolrClientTest, TestExtendedDismaxParser, DisMaxRequestHandlerTest, SolrPluginUtilsTest, UUIDFieldTest, WordBreakSolrSpellCheckerTest, TestUtils, SuggesterFSTTest, DistributedSpellCheckComponentTest, TestPerFieldSimilarity, SolrCmdDistributorTest, LeaderElectionIntegrationTest, TestSuggestSpellingConverter, TestRandomFaceting, MBeansHandlerTest, StatelessScriptUpdateProcessorFactoryTest, CacheHeaderTest, CurrencyFieldTest, RAMDirectoryFactoryTest, TestDocumentBuilder, BinaryUpdateRequestHandlerTest, TestFastLRUCache, BasicDistributedZk2Test, ZkControllerTest, ResourceLoaderTest, TestCharFilters, DirectUpdateHandlerTest, BasicDistributedZkTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=BasicDistributedZkTest -Dtests.seed=DEF869E92B89F42C -Dtests.slow=true -Dtests.locale=nl -Dtests.timezone=America/Juneau -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR   0.00s | BasicDistributedZkTest (suite) <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=540 closes=539
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([DEF869E92B89F42C]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:223)
[junit4:junit4]    > 	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:89)
[junit4:junit4]    > 	at sun.reflect.GeneratedMethodAccessor16.invoke(Unknown Source)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:601)
[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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
[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:345)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] Completed in 230.15s, 1 test, 1 failure <<< FAILURES!

[...truncated 353 lines...]
BUILD FAILED
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\build.xml:29: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:144: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\lucene\module-build.xml:62: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1055: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:741: There were test failures: 227 suites, 915 tests, 1 suite-level error, 11 ignored (6 assumptions)

Total time: 37 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Description set: Java: 32bit/jdk1.7.0_06 -server -XX:+UseConcMarkSweepGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message