lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-trunk-java7 - Build # 3286 - Still Failing
Date Mon, 08 Oct 2012 12:12:57 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-java7/3286/

1 tests failed.
REGRESSION:  org.apache.solr.cloud.BasicDistributedZkTest.testDistribSearch

Error Message:
Server at http://127.0.0.1:60279/solr/awholynewcollection_7 returned non ok status:500, message:Server Error

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:60279/solr/awholynewcollection_7 returned non ok status:500, message:Server Error
	at __randomizedtesting.SeedInfo.seed([F08D6FB55DC19F4A:716BE1AD2A9EFF76]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:372)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
	at org.apache.solr.cloud.BasicDistributedZkTest.waitForNon403or404or503(BasicDistributedZkTest.java:549)
	at org.apache.solr.cloud.BasicDistributedZkTest.testCollectionsAPI(BasicDistributedZkTest.java:386)
	at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:331)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java: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$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 8712 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 4 T340 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZkTest-1349697595882
[junit4:junit4]   2> 6 T340 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 10 T341 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 10 T341 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 11 T341 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 108 T340 oasc.ZkTestServer.run start zk server on port:60254
[junit4:junit4]   2> 108 T340 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60254 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@464095c2
[junit4:junit4]   2> 109 T346 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:60254
[junit4:junit4]   2> 109 T340 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 110 T346 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:60254, initiating session
[junit4:junit4]   2> 111 T342 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:60255
[junit4:junit4]   2> 111 T342 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:60255
[junit4:junit4]   2> 112 T344 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 115 T344 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a403ee9f00000 with negotiated timeout 10000 for client /140.211.11.196:60255
[junit4:junit4]   2> 115 T346 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:60254, sessionid = 0x13a403ee9f00000, negotiated timeout = 10000
[junit4:junit4]   2> 115 T347 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@464095c2 name:ZooKeeperConnection Watcher:127.0.0.1:60254 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 115 T340 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 116 T340 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 119 T345 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a403ee9f00000
[junit4:junit4]   2> 128 T347 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 128 T340 oaz.ZooKeeper.close Session: 0x13a403ee9f00000 closed
[junit4:junit4]   2> 128 T342 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:60255 which had sessionid 0x13a403ee9f00000
[junit4:junit4]   2> 128 T340 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60254/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@6a20c66d
[junit4:junit4]   2> 129 T348 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:60254
[junit4:junit4]   2> 129 T340 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 130 T348 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:60254, initiating session
[junit4:junit4]   2> 130 T342 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:60256
[junit4:junit4]   2> 131 T342 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:60256
[junit4:junit4]   2> 132 T344 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a403ee9f00001 with negotiated timeout 10000 for client /140.211.11.196:60256
[junit4:junit4]   2> 132 T348 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:60254, sessionid = 0x13a403ee9f00001, negotiated timeout = 10000
[junit4:junit4]   2> 133 T349 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a20c66d name:ZooKeeperConnection Watcher:127.0.0.1:60254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 133 T340 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 133 T340 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 143 T340 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 147 T340 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 150 T340 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 158 T340 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 159 T340 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 172 T340 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 173 T340 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 277 T340 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 278 T340 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 282 T340 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 283 T340 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 286 T340 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 287 T340 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 296 T340 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 297 T340 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 300 T340 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 301 T340 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 305 T340 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 306 T340 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 310 T340 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 310 T340 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 314 T340 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 315 T340 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 318 T345 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a403ee9f00001
[junit4:junit4]   2> 319 T349 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 319 T340 oaz.ZooKeeper.close Session: 0x13a403ee9f00001 closed
[junit4:junit4]   2> 320 T342 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:60256 which had sessionid 0x13a403ee9f00001
[junit4:junit4]   2> 1029 T340 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1304 T340 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:60258
[junit4:junit4]   2> 1334 T340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1335 T340 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349697596196
[junit4:junit4]   2> 1335 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349697596196/'
[junit4:junit4]   2> 1368 T340 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1369 T340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1369 T340 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349697596196
[junit4:junit4]   2> 1370 T340 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349697596196/solr.xml
[junit4:junit4]   2> 1370 T340 oasc.CoreContainer.<init> New CoreContainer 102379522
[junit4:junit4]   2> 1371 T340 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349697596196/'
[junit4:junit4]   2> 1371 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349697596196/'
[junit4:junit4]   2> 1398 T340 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1411 T340 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:60254/solr
[junit4:junit4]   2> 1411 T340 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60254/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@69afec7
[junit4:junit4]   2> 1412 T360 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:60254
[junit4:junit4]   2> 1412 T340 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1414 T360 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:60254, initiating session
[junit4:junit4]   2> 1414 T342 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:60259
[junit4:junit4]   2> 1415 T342 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:60259
[junit4:junit4]   2> 1451 T344 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a403ee9f00002 with negotiated timeout 5000 for client /140.211.11.196:60259
[junit4:junit4]   2> 1451 T360 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:60254, sessionid = 0x13a403ee9f00002, negotiated timeout = 5000
[junit4:junit4]   2> 1451 T361 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@69afec7 name:ZooKeeperConnection Watcher:127.0.0.1:60254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1452 T340 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1482 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1513 T340 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 1538 T340 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60258_solr
[junit4:junit4]   2> 1539 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60258_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60258_solr
[junit4:junit4]   2> 1540 T340 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60258_solr
[junit4:junit4]   2> 1580 T340 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1580 T340 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1581 T340 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1581 T340 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1582 T340 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1582 T340 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1583 T340 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1583 T340 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1584 T340 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1584 T340 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1603 T340 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 1630 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 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> 1632 T340 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 1635 T340 oasc.Overseer.start Overseer (id=88453781644115970-127.0.0.1:60258_solr-n_0000000000) starting
[junit4:junit4]   2> 1636 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1643 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1645 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1647 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1654 T363 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 1655 T340 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 1659 T340 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1661 T362 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 1672 T340 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349697596196/collection1
[junit4:junit4]   2> 1673 T340 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 1674 T340 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1674 T340 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 1676 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349697596196/collection1/'
[junit4:junit4]   2> 1678 T340 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349697596196/collection1/lib/README' to classloader
[junit4:junit4]   2> 1679 T340 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349697596196/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1736 T340 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 1815 T340 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1916 T340 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1932 T340 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2431 T340 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2440 T340 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2443 T340 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2458 T340 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2463 T340 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2467 T340 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2468 T340 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2469 T340 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-controljetty-1349697596196/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/control/data/
[junit4:junit4]   2> 2470 T340 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e401b01
[junit4:junit4]   2> 2493 T340 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/control/data/index/
[junit4:junit4]   2> 2494 T340 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2495 T340 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/control/data/index forceNew:false
[junit4:junit4]   2> 2528 T340 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2529 T340 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2529 T340 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2531 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2532 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2532 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2532 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2533 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2533 T340 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2534 T340 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2534 T340 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2535 T340 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2536 T340 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2536 T340 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2537 T340 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2537 T340 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2537 T340 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2538 T340 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2538 T340 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2539 T340 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2539 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2540 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2540 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2541 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2541 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2542 T340 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2542 T340 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2543 T340 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2551 T340 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2555 T340 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2557 T340 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> 2560 T340 oass.SolrIndexSearcher.<init> Opening Searcher@17fa8f76 main
[junit4:junit4]   2> 2561 T340 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2561 T340 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2562 T340 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2569 T340 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2626 T364 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17fa8f76 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2630 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 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> 2642 T340 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 2642 T340 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:60258/solr shardId:control_shard
[junit4:junit4]   2> 2657 T340 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 2668 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2674 T340 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 2686 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 type:create cxid:0x64 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 2702 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 type:delete cxid:0x6b 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> 2703 T340 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2704 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 type:create cxid:0x6e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2706 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3207 T340 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3208 T340 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3211 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3214 T340 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60258/solr/collection1/
[junit4:junit4]   2> 3214 T340 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3215 T340 oasc.SyncStrategy.syncToMe http://127.0.0.1:60258/solr/collection1/ has no replicas
[junit4:junit4]   2> 3215 T340 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60258/solr/collection1/
[junit4:junit4]   2> 3216 T340 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 3238 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3241 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00002 type:create cxid:0x8d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3754 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3776 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3813 T340 oasc.ZkController.register We are http://127.0.0.1:60258/solr/collection1/ and leader is http://127.0.0.1:60258/solr/collection1/
[junit4:junit4]   2> 3813 T340 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60258/solr
[junit4:junit4]   2> 3814 T340 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3818 T340 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3819 T340 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3820 T340 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3898 T340 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4085 T340 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4089 T340 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:60262
[junit4:junit4]   2> 4089 T340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4090 T340 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349697599799
[junit4:junit4]   2> 4090 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349697599799/'
[junit4:junit4]   2> 4132 T340 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4133 T340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4133 T340 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349697599799
[junit4:junit4]   2> 4134 T340 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349697599799/solr.xml
[junit4:junit4]   2> 4134 T340 oasc.CoreContainer.<init> New CoreContainer 1982720651
[junit4:junit4]   2> 4135 T340 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349697599799/'
[junit4:junit4]   2> 4135 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349697599799/'
[junit4:junit4]   2> 4169 T340 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4185 T340 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:60254/solr
[junit4:junit4]   2> 4186 T340 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60254/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@15086ae5
[junit4:junit4]   2> 4187 T374 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:60254
[junit4:junit4]   2> 4187 T340 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4189 T374 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:60254, initiating session
[junit4:junit4]   2> 4189 T342 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:60263
[junit4:junit4]   2> 4189 T342 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:60263
[junit4:junit4]   2> 4192 T344 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a403ee9f00003 with negotiated timeout 5000 for client /140.211.11.196:60263
[junit4:junit4]   2> 4192 T374 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:60254, sessionid = 0x13a403ee9f00003, negotiated timeout = 5000
[junit4:junit4]   2> 4192 T375 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15086ae5 name:ZooKeeperConnection Watcher:127.0.0.1:60254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4193 T340 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4194 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4207 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4209 T340 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60262_solr
[junit4:junit4]   2> 4210 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60262_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60262_solr
[junit4:junit4]   2> 4212 T340 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60262_solr
[junit4:junit4]   2> 4220 T361 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4221 T340 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4222 T340 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4223 T340 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4223 T340 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4224 T340 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4224 T340 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4225 T340 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4226 T340 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4226 T340 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4227 T340 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4257 T340 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4265 T340 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349697599799/collection1
[junit4:junit4]   2> 4265 T340 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4266 T340 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4266 T340 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4268 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349697599799/collection1/'
[junit4:junit4]   2> 4270 T340 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349697599799/collection1/lib/README' to classloader
[junit4:junit4]   2> 4270 T340 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349697599799/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4282 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4293 T375 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4293 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4357 T340 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4428 T340 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4530 T340 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4545 T340 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5283 T340 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5297 T340 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5302 T340 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5321 T340 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5328 T340 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5333 T340 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5335 T340 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5337 T340 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty1-1349697599799/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty1/
[junit4:junit4]   2> 5337 T340 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e401b01
[junit4:junit4]   2> 5339 T340 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty1/index/
[junit4:junit4]   2> 5340 T340 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5341 T340 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty1/index forceNew:false
[junit4:junit4]   2> 5373 T340 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5374 T340 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5374 T340 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5377 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5378 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5379 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5379 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5380 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5381 T340 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5381 T340 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5382 T340 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5383 T340 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5384 T340 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5385 T340 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5385 T340 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5386 T340 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5386 T340 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5387 T340 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5388 T340 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5389 T340 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5389 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5390 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5391 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5392 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5393 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5393 T340 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5394 T340 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5395 T340 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5408 T340 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5413 T340 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5415 T340 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> 5419 T340 oass.SolrIndexSearcher.<init> Opening Searcher@2f1c1b75 main
[junit4:junit4]   2> 5420 T340 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5421 T340 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5422 T340 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5430 T340 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5491 T376 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2f1c1b75 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5805 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5831 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5831 T375 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6016 T340 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6016 T340 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:60262/solr shardId:shard1
[junit4:junit4]   2> 6018 T340 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 6043 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00003 type:delete cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 6044 T340 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 6045 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6048 T340 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 6049 T340 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 6050 T340 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60262/solr/collection1/
[junit4:junit4]   2> 6050 T340 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 6051 T340 oasc.SyncStrategy.syncToMe http://127.0.0.1:60262/solr/collection1/ has no replicas
[junit4:junit4]   2> 6052 T340 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60262/solr/collection1/
[junit4:junit4]   2> 6053 T340 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 6059 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6337 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6390 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6390 T375 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6426 T340 oasc.ZkController.register We are http://127.0.0.1:60262/solr/collection1/ and leader is http://127.0.0.1:60262/solr/collection1/
[junit4:junit4]   2> 6426 T340 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60262/solr
[junit4:junit4]   2> 6427 T340 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 6444 T340 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6446 T340 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 6446 T340 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6447 T340 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6624 T340 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 6627 T340 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:60279
[junit4:junit4]   2> 6628 T340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6628 T340 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349697602339
[junit4:junit4]   2> 6629 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349697602339/'
[junit4:junit4]   2> 6670 T340 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6671 T340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6671 T340 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349697602339
[junit4:junit4]   2> 6672 T340 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349697602339/solr.xml
[junit4:junit4]   2> 6672 T340 oasc.CoreContainer.<init> New CoreContainer 1109115142
[junit4:junit4]   2> 6673 T340 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349697602339/'
[junit4:junit4]   2> 6673 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349697602339/'
[junit4:junit4]   2> 6706 T340 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6722 T340 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:60254/solr
[junit4:junit4]   2> 6723 T340 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60254/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@12fd56b3
[junit4:junit4]   2> 6724 T386 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:60254
[junit4:junit4]   2> 6724 T340 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6725 T386 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:60254, initiating session
[junit4:junit4]   2> 6726 T342 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:60280
[junit4:junit4]   2> 6726 T342 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:60280
[junit4:junit4]   2> 6731 T344 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a403ee9f00004 with negotiated timeout 5000 for client /140.211.11.196:60280
[junit4:junit4]   2> 6731 T386 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:60254, sessionid = 0x13a403ee9f00004, negotiated timeout = 5000
[junit4:junit4]   2> 6732 T387 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12fd56b3 name:ZooKeeperConnection Watcher:127.0.0.1:60254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6732 T340 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6733 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6742 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6744 T340 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60279_solr
[junit4:junit4]   2> 6745 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60279_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60279_solr
[junit4:junit4]   2> 6746 T340 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60279_solr
[junit4:junit4]   2> 6748 T375 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 6748 T361 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 6749 T340 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6750 T340 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6750 T340 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6751 T340 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6751 T340 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6752 T340 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6752 T340 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6752 T340 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6753 T340 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6753 T340 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6773 T340 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6780 T340 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349697602339/collection1
[junit4:junit4]   2> 6781 T340 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6782 T340 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6782 T340 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6784 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349697602339/collection1/'
[junit4:junit4]   2> 6785 T340 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349697602339/collection1/lib/README' to classloader
[junit4:junit4]   2> 6786 T340 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349697602339/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6841 T340 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 6912 T340 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6947 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6966 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6966 T375 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7013 T387 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7013 T340 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 7029 T340 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7664 T340 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 7673 T340 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7676 T340 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7693 T340 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7699 T340 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7704 T340 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7706 T340 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7708 T340 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty2-1349697602339/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty2/
[junit4:junit4]   2> 7708 T340 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e401b01
[junit4:junit4]   2> 7709 T340 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty2/index/
[junit4:junit4]   2> 7710 T340 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 7710 T340 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty2/index forceNew:false
[junit4:junit4]   2> 7723 T340 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7724 T340 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 7724 T340 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 7727 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7727 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 7727 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7728 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 7728 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 7729 T340 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7729 T340 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7730 T340 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7730 T340 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7731 T340 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 7731 T340 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 7732 T340 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7732 T340 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 7733 T340 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 7733 T340 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 7734 T340 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7734 T340 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7735 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7735 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7736 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7736 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7737 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7737 T340 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7738 T340 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 7738 T340 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 7747 T340 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7750 T340 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 7752 T340 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> 7755 T340 oass.SolrIndexSearcher.<init> Opening Searcher@39feb90f main
[junit4:junit4]   2> 7756 T340 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7756 T340 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7756 T340 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 7773 T340 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 7816 T388 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@39feb90f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7972 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7988 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7988 T375 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7988 T387 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8326 T340 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 8326 T340 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:60279/solr shardId:shard2
[junit4:junit4]   2> 8327 T340 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 8343 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00004 type:delete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> 8344 T340 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 8344 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00004 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8355 T340 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 8355 T340 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 8355 T340 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60279/solr/collection1/
[junit4:junit4]   2> 8356 T340 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 8356 T340 oasc.SyncStrategy.syncToMe http://127.0.0.1:60279/solr/collection1/ has no replicas
[junit4:junit4]   2> 8356 T340 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60279/solr/collection1/
[junit4:junit4]   2> 8356 T340 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 8360 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00004 type:create cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8494 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8509 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8510 T387 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8510 T375 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8516 T340 oasc.ZkController.register We are http://127.0.0.1:60279/solr/collection1/ and leader is http://127.0.0.1:60279/solr/collection1/
[junit4:junit4]   2> 8516 T340 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60279/solr
[junit4:junit4]   2> 8517 T340 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 8519 T340 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8520 T340 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 8520 T340 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8521 T340 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8721 T340 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 8725 T340 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:60283
[junit4:junit4]   2> 8726 T340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8726 T340 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349697604412
[junit4:junit4]   2> 8727 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349697604412/'
[junit4:junit4]   2> 8784 T340 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8785 T340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8786 T340 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349697604412
[junit4:junit4]   2> 8786 T340 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349697604412/solr.xml
[junit4:junit4]   2> 8787 T340 oasc.CoreContainer.<init> New CoreContainer 50967178
[junit4:junit4]   2> 8788 T340 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349697604412/'
[junit4:junit4]   2> 8788 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349697604412/'
[junit4:junit4]   2> 8833 T340 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8855 T340 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:60254/solr
[junit4:junit4]   2> 8856 T340 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60254/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@52a1c92
[junit4:junit4]   2> 8857 T398 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:60254
[junit4:junit4]   2> 8857 T340 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8859 T398 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:60254, initiating session
[junit4:junit4]   2> 8859 T342 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:60284
[junit4:junit4]   2> 8859 T342 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:60284
[junit4:junit4]   2> 8862 T344 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a403ee9f00005 with negotiated timeout 5000 for client /140.211.11.196:60284
[junit4:junit4]   2> 8862 T398 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:60254, sessionid = 0x13a403ee9f00005, negotiated timeout = 5000
[junit4:junit4]   2> 8862 T399 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@52a1c92 name:ZooKeeperConnection Watcher:127.0.0.1:60254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8862 T340 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8863 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8876 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8878 T340 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60283_solr
[junit4:junit4]   2> 8878 T345 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a403ee9f00005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60283_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60283_solr
[junit4:junit4]   2> 8880 T340 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60283_solr
[junit4:junit4]   2> 8887 T361 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8887 T387 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8887 T375 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8888 T340 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8889 T340 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8889 T340 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8890 T340 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8890 T340 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8891 T340 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8891 T340 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8892 T340 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8892 T340 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8892 T340 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8911 T340 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8921 T340 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349697604412/collection1
[junit4:junit4]   2> 8921 T340 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8922 T340 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8923 T340 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8925 T340 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349697604412/collection1/'
[junit4:junit4]   2> 8927 T340 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349697604412/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8928 T340 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349697604412/collection1/lib/README' to classloader
[junit4:junit4]   2> 9003 T340 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 9022 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9037 T387 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9037 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9037 T399 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9037 T375 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9103 T340 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9204 T340 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9217 T340 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9960 T340 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9972 T340 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9977 T340 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9994 T340 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10000 T340 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10005 T340 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10007 T340 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10008 T340 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-jetty3-1349697604412/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty3/
[junit4:junit4]   2> 10009 T340 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3e401b01
[junit4:junit4]   2> 10010 T340 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty3/index/
[junit4:junit4]   2> 10011 T340 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 10012 T340 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty3/index forceNew:false
[junit4:junit4]   2> 10043 T340 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10044 T340 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10045 T340 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 10048 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 10049 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 10049 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 10050 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 10051 T340 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 10051 T340 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 10052 T340 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 10053 T340 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 10053 T340 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 10054 T340 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 10055 T340 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 10056 T340 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 10057 T340 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 10057 T340 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 10058 T340 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 10058 T340 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 10059 T340 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10060 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10061 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10062 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10062 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10063 T340 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10064 T340 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10065 T340 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 10065 T340 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 10078 T340 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 10083 T340 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 10085 T340 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> 10089 T340 oass.SolrIndexSearcher.<init> Opening Searcher@19114ef5 main
[junit4:junit4]   2> 10090 T340 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 10091 T340 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 10092 T340 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 10100 T340 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 10154 T400 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19114ef5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 10547 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10561 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10561 T399 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10561 T375 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10561 T387 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10663 T340 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 10664 T340 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:60283/solr shardId:shard1
[junit4:junit4]   2> 10668 T340 oasc.ZkController.register We are http://127.0.0.1:60283/solr/collection1/ and leader is http://127.0.0.1:60262/solr/collection1/
[junit4:junit4]   2> 10668 T340 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60283/solr
[junit4:junit4]   2> 10669 T340 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 10669 T340 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C27 name=collection1 org.apache.solr.core.SolrCore@16e81e5f url=http://127.0.0.1:60283/solr/collection1 node=127.0.0.1:60283_solr
[junit4:junit4]   2> 10677 T401 C27 P60283 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 10677 T340 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10702 T401 C27 P60283 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 10703 T340 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 10704 T340 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 10704 T401 C27 P60283 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:60262/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 10704 T340 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10714 T401 C27 P60283 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 10726 T340 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60254/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@26295abd
[junit4:junit4]   2> 10727 T402 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:60254
[junit4:junit4]   2> 10727 T340 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10728 T402 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:60254, initiating session
[junit4:junit4]   2> 10729 T342 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:60285
[junit4:junit4]   2> 10729 T342 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:60285
[junit4:junit4]   2> 10731 T344 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a403ee9f00006 with negotiated timeout 10000 for client /140.211.11.196:60285
[junit4:junit4]   2> 10731 T402 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:60254, sessionid = 0x13a403ee9f00006, negotiated timeout = 10000
[junit4:junit4]   2> 10732 T403 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@26295abd name:ZooKeeperConnection Watcher:127.0.0.1:60254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10732 T340 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10733 T340 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 10734 T401 C27 P60283 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:60283/solr START replicas=[http://127.0.0.1:60262/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 10738 T340 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 10739 T340 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 10746 T401 C27 P60283 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 10747 T401 C27 P60283 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 10747 T401 C27 P60283 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 10747 T401 C27 P60283 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10753 T340 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10755 T340 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):1800
[junit4:junit4]   2> 10756 T340 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11067 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11081 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11081 T387 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11081 T375 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11081 T399 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11081 T403 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11758 T340 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C28 name=collection1 org.apache.solr.core.SolrCore@2a836075 url=http://127.0.0.1:60262/solr/collection1 node=127.0.0.1:60262_solr
[junit4:junit4]   2> 11897 T368 C28 P60262 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=14 
[junit4:junit4]   2> 12760 T340 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13762 T340 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C27_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:60283_solr, base_url=http://127.0.0.1:60283/solr}
[junit4:junit4]   2> 14011 T401 C27 P60283 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 14011 T401 C27 P60283 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 14011 T401 C27 P60283 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:60262/solr/collection1/. core=collection1
[junit4:junit4]   2> 14011 T401 C27 P60283 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 14049 T369 C28 P60262 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16
[junit4:junit4]   2> 14063 T369 C28 P60262 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14068 T369 C28 P60262 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14069 T369 C28 P60262 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 14070 T369 C28 P60262 oass.SolrIndexSearcher.<init> Opening Searcher@5de8bd02 main
[junit4:junit4]   2> 14071 T369 C28 P60262 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14072 T376 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5de8bd02 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 14073 T369 C28 P60262 /update {waitSearcher=true&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 37
[junit4:junit4]   2> 14074 T401 C27 P60283 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 14075 T401 C27 P60283 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 14097 T401 C27 P60283 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 14155 T370 C28 P60262 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 14155 T370 C28 P60262 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=56 
[junit4:junit4]   2> 14161 T401 C27 P60283 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14161 T401 C27 P60283 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 14162 T401 C27 P60283 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14165 T401 C27 P60283 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty3/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 14165 T401 C27 P60283 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 14169 T401 C27 P60283 oass.SolrIndexSearcher.<init> Opening Searcher@59df0a37 main
[junit4:junit4]   2> 14169 T401 C27 P60283 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14171 T400 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@59df0a37 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 14172 T401 C27 P60283 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 14172 T401 C27 P60283 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 14173 T401 C27 P60283 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 14599 T362 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14618 T387 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14618 T375 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14618 T399 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14618 T403 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14618 T361 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14764 T340 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C29 name=collection1 org.apache.solr.core.SolrCore@681f95bd url=http://127.0.0.1:60258/solr/collection1 node=127.0.0.1:60258_solr
[junit4:junit4]   2> 14869 T353 C29 P60258 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14870 T353 C29 P60258 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 14872 T353 C29 P60258 oass.SolrIndexSearcher.<init> Opening Searcher@76bba383 realtime
[junit4:junit4]   2> 14872 T353 C29 P60258 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1415260521880354816)} 0 31
[junit4:junit4]   2> 14873 T340 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60254/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@18ec2a5b
[junit4:junit4]   2> 14874 T405 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:60254
[junit4:junit4]   2> 14874 T340 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 14875 T405 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:60254, initiating session
[junit4:junit4]   2> 14875 T342 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:60295
[junit4:junit4]   2> 14876 T342 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:60295
[junit4:junit4]   2> 14877 T344 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a403ee9f00007 with negotiated timeout 10000 for client /140.211.11.196:60295
[junit4:junit4]   2> 14877 T405 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:60254, sessionid = 0x13a403ee9f00007, negotiated timeout = 10000
[junit4:junit4]   2> 14878 T406 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18ec2a5b name:ZooKeeperConnection Watcher:127.0.0.1:60254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 14878 T340 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 14879 T340 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 14904 T371 C28 P60262 oass.SolrIndexSearcher.<init> Opening Searcher@5a525387 realtime
[junit4:junit4]   2>  C27_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:60283_solr, base_url=http://127.0.0.1:60283/solr}
[junit4:junit4]   2> 14935 T391 C27 P60283 oass.SolrIndexSearcher.<init> Opening Searcher@3e6cb3f realtime
[junit4:junit4]   2> 14936 T391 C27 P60283 REQ /update {update.distrib=FROMLEADER&_version_=-1415260521919152128&update.from=http://127.0.0.1:60262/solr/collection1/&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> ASYNC  NEW_CORE C30 name=collection1 org.apache.solr.core.SolrCore@42c75ab0 url=http://127.0.0.1:60279/solr/collection1 node=127.0.0.1:60279_solr
[junit4:junit4]   2> 14938 T379 C30 P60279 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14939 T379 C30 P60279 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 14940 T379 C30 P60279 oass.SolrIndexSearcher.<init> Opening Searcher@70b96528 realtime
[junit4:junit4]   2> 14941 T379 C30 P60279 REQ /update {update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 14942 T371 C28 P60262 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1415260521919152128)} 0 48
[junit4:junit4]   2> 14952 T353 C29 P60258 /update {wt=javabin&version=2} {add=[1 (1415260521967386624)]} 0 5
[junit4:junit4]   2> 14976 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 14977 T371 C28 P60262 /update {wt=javabin&version=2} {add=[1 (1415260521975775232)]} 0 21
[junit4:junit4]   2> 14982 T353 C29 P60258 /update {wt=javabin&version=2} {add=[2 (1415260522001989632)]} 0 2
[junit4:junit4]   2> 14999 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 15000 T371 C28 P60262 /update {wt=javabin&version=2} {add=[2]} 0 14
[junit4:junit4]   2> 15005 T353 C29 P60258 /update {wt=javabin&version=2} {add=[3 (1415260522026106880)]} 0 1
[junit4:junit4]   2> 15013 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15014 T371 C28 P60262 /update {wt=javabin&version=2} {add=[3]} 0 6
[junit4:junit4]   2> 15019 T353 C29 P60258 /update {wt=javabin&version=2} {add=[4 (1415260522040786944)]} 0 2
[junit4:junit4]   2> 15033 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15033 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 15034 T380 C30 P60279 /update {wt=javabin&version=2} {add=[4]} 0 12
[junit4:junit4]   2> 15039 T353 C29 P60258 /update {wt=javabin&version=2} {add=[5 (1415260522061758464)]} 0 2
[junit4:junit4]   2> 15045 T380 C30 P60279 /update {wt=javabin&version=2} {add=[5 (1415260522067001344)]} 0 3
[junit4:junit4]   2> 15050 T353 C29 P60258 /update {wt=javabin&version=2} {add=[6 (1415260522073292800)]} 0 2
[junit4:junit4]   2> 15058 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15059 T371 C28 P60262 /update {wt=javabin&version=2} {add=[6]} 0 6
[junit4:junit4]   2> 15064 T353 C29 P60258 /update {wt=javabin&version=2} {add=[7 (1415260522087972864)]} 0 2
[junit4:junit4]   2> 15069 T380 C30 P60279 /update {wt=javabin&version=2} {add=[7 (1415260522093215744)]} 0 2
[junit4:junit4]   2> 15074 T353 C29 P60258 /update {wt=javabin&version=2} {add=[8 (1415260522098458624)]} 0 2
[junit4:junit4]   2> 15084 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15085 T371 C28 P60262 /update {wt=javabin&version=2} {add=[8 (1415260522103701504)]} 0 8
[junit4:junit4]   2> 15090 T353 C29 P60258 /update {wt=javabin&version=2} {add=[9 (1415260522115235840)]} 0 2
[junit4:junit4]   2> 15098 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15099 T371 C28 P60262 /update {wt=javabin&version=2} {add=[9]} 0 6
[junit4:junit4]   2> 15131 T353 C29 P60258 /update {wt=javabin&version=2} {add=[10 (1415260522129915904)]} 0 29
[junit4:junit4]   2> 15141 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15142 T371 C28 P60262 /update {wt=javabin&version=2} {add=[10 (1415260522163470336)]} 0 8
[junit4:junit4]   2> 15150 T353 C29 P60258 /update {wt=javabin&version=2} {add=[11 (1415260522176053248)]} 0 3
[junit4:junit4]   2> 15164 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15165 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 15165 T380 C30 P60279 /update {wt=javabin&version=2} {add=[11]} 0 12
[junit4:junit4]   2> 15172 T353 C29 P60258 /update {wt=javabin&version=2} {add=[12 (1415260522200170496)]} 0 2
[junit4:junit4]   2> 15183 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15183 T371 C28 P60262 /update {wt=javabin&version=2} {add=[12 (1415260522206461952)]} 0 7
[junit4:junit4]   2> 15189 T353 C29 P60258 /update {wt=javabin&version=2} {add=[13 (1415260522217996288)]} 0 2
[junit4:junit4]   2> 15204 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15205 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 15205 T380 C30 P60279 /update {wt=javabin&version=2} {add=[13]} 0 13
[junit4:junit4]   2> 15211 T353 C29 P60258 /update {wt=javabin&version=2} {add=[14 (1415260522242113536)]} 0 1
[junit4:junit4]   2> 15221 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15222 T371 C28 P60262 /update {wt=javabin&version=2} {add=[14 (1415260522247356416)]} 0 7
[junit4:junit4]   2> 15228 T353 C29 P60258 /update {wt=javabin&version=2} {add=[15 (1415260522259939328)]} 0 1
[junit4:junit4]   2> 15241 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15242 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 15243 T380 C30 P60279 /update {wt=javabin&version=2} {add=[15]} 0 12
[junit4:junit4]   2> 15249 T353 C29 P60258 /update {wt=javabin&version=2} {add=[16 (1415260522280910848)]} 0 2
[junit4:junit4]   2> 15317 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=28 
[junit4:junit4]   2> 15318 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=62 
[junit4:junit4]   2> 15319 T380 C30 P60279 /update {wt=javabin&version=2} {add=[16]} 0 67
[junit4:junit4]   2> 15329 T353 C29 P60258 /update {wt=javabin&version=2} {add=[17 (1415260522364796928)]} 0 5
[junit4:junit4]   2> 15345 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 15346 T371 C28 P60262 /update {wt=javabin&version=2} {add=[17]} 0 12
[junit4:junit4]   2> 15351 T353 C29 P60258 /update {wt=javabin&version=2} {add=[100 (1415260522388914176)]} 0 2
[junit4:junit4]   2> 15367 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 15368 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 15369 T380 C30 P60279 /update {wt=javabin&version=2} {add=[100]} 0 15
[junit4:junit4]   2> 15374 T353 C29 P60258 /update {wt=javabin&version=2} {add=[101 (1415260522413031424)]} 0 1
[junit4:junit4]   2> 15385 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15386 T371 C28 P60262 /update {wt=javabin&version=2} {add=[101 (1415260522419322880)]} 0 7
[junit4:junit4]   2> 15420 T353 C29 P60258 /update {wt=javabin&version=2} {add=[102 (1415260522430857216)]} 0 30
[junit4:junit4]   2> 15431 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15432 T371 C28 P60262 /update {wt=javabin&version=2} {add=[102 (1415260522467557376)]} 0 7
[junit4:junit4]   2> 15439 T353 C29 P60258 /update {wt=javabin&version=2} {add=[103 (1415260522479091712)]} 0 3
[junit4:junit4]   2> 15444 T380 C30 P60279 /update {wt=javabin&version=2} {add=[103 (1415260522486431744)]} 0 2
[junit4:junit4]   2> 15449 T353 C29 P60258 /update {wt=javabin&version=2} {add=[104 (1415260522491674624)]} 0 1
[junit4:junit4]   2> 15458 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15459 T371 C28 P60262 /update {wt=javabin&version=2} {add=[104]} 0 6
[junit4:junit4]   2> 15464 T353 C29 P60258 /update {wt=javabin&version=2} {add=[105 (1415260522507403264)]} 0 1
[junit4:junit4]   2> 15500 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=29 
[junit4:junit4]   2> 15501 T371 C28 P60262 /update {wt=javabin&version=2} {add=[105]} 0 33
[junit4:junit4]   2> 15507 T353 C29 P60258 /update {wt=javabin&version=2} {add=[106 (1415260522551443456)]} 0 2
[junit4:junit4]   2> 15517 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 15517 T371 C28 P60262 /update {wt=javabin&version=2} {add=[106]} 0 6
[junit4:junit4]   2> 15523 T353 C29 P60258 /update {wt=javabin&version=2} {add=[107 (1415260522569269248)]} 0 1
[junit4:junit4]   2> 15532 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15533 T371 C28 P60262 /update {wt=javabin&version=2} {add=[107 (1415260522574512128)]} 0 6
[junit4:junit4]   2> 15538 T353 C29 P60258 /update {wt=javabin&version=2} {add=[108 (1415260522584997888)]} 0 2
[junit4:junit4]   2> 15552 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15553 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 15554 T380 C30 P60279 /update {wt=javabin&version=2} {add=[108]} 0 12
[junit4:junit4]   2> 15559 T353 C29 P60258 /update {wt=javabin&version=2} {add=[109 (1415260522607017984)]} 0 2
[junit4:junit4]   2> 15571 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15572 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 15573 T380 C30 P60279 /update {wt=javabin&version=2} {add=[109]} 0 10
[junit4:junit4]   2> 15578 T353 C29 P60258 /update {wt=javabin&version=2} {add=[110 (1415260522626940928)]} 0 2
[junit4:junit4]   2> 15590 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15591 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 15591 T380 C30 P60279 /update {wt=javabin&version=2} {add=[110]} 0 10
[junit4:junit4]   2> 15597 T353 C29 P60258 /update {wt=javabin&version=2} {add=[111 (1415260522646863872)]} 0 2
[junit4:junit4]   2> 15606 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15607 T371 C28 P60262 /update {wt=javabin&version=2} {add=[111 (1415260522652106752)]} 0 7
[junit4:junit4]   2> 15638 T353 C29 P60258 /update {wt=javabin&version=2} {add=[112 (1415260522662592512)]} 0 28
[junit4:junit4]   2> 15646 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15647 T371 C28 P60262 /update {wt=javabin&version=2} {add=[112]} 0 6
[junit4:junit4]   2> 15653 T353 C29 P60258 /update {wt=javabin&version=2} {add=[113 (1415260522704535552)]} 0 3
[junit4:junit4]   2> 15663 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15664 T371 C28 P60262 /update {wt=javabin&version=2} {add=[113 (1415260522711875584)]} 0 7
[junit4:junit4]   2> 15669 T353 C29 P60258 /update {wt=javabin&version=2} {add=[114 (1415260522722361344)]} 0 2
[junit4:junit4]   2> 15730 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=27 
[junit4:junit4]   2> 15731 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=55 
[junit4:junit4]   2> 15732 T380 C30 P60279 /update {wt=javabin&version=2} {add=[114]} 0 60
[junit4:junit4]   2> 15737 T353 C29 P60258 /update {wt=javabin&version=2} {add=[115 (1415260522793664512)]} 0 2
[junit4:junit4]   2> 15742 T380 C30 P60279 /update {wt=javabin&version=2} {add=[115 (1415260522798907392)]} 0 1
[junit4:junit4]   2> 15747 T353 C29 P60258 /update {wt=javabin&version=2} {add=[116 (1415260522804150272)]} 0 2
[junit4:junit4]   2> 15752 T380 C30 P60279 /update {wt=javabin&version=2} {add=[116 (1415260522809393152)]} 0 2
[junit4:junit4]   2> 15757 T353 C29 P60258 /update {wt=javabin&version=2} {add=[117 (1415260522814636032)]} 0 1
[junit4:junit4]   2> 15762 T380 C30 P60279 /update {wt=javabin&version=2} {add=[117 (1415260522819878912)]} 0 1
[junit4:junit4]   2> 15767 T353 C29 P60258 /update {wt=javabin&version=2} {add=[118 (1415260522825121792)]} 0 2
[junit4:junit4]   2> 15783 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 15784 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=11 
[junit4:junit4]   2> 15784 T380 C30 P60279 /update {wt=javabin&version=2} {add=[118]} 0 14
[junit4:junit4]   2> 15789 T353 C29 P60258 /update {wt=javabin&version=2} {add=[119 (1415260522848190464)]} 0 2
[junit4:junit4]   2> 15794 T380 C30 P60279 /update {wt=javabin&version=2} {add=[119 (1415260522853433344)]} 0 2
[junit4:junit4]   2> 15799 T353 C29 P60258 /update {wt=javabin&version=2} {add=[120 (1415260522858676224)]} 0 2
[junit4:junit4]   2> 15807 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15808 T371 C28 P60262 /update {wt=javabin&version=2} {add=[120]} 0 6
[junit4:junit4]   2> 15813 T353 C29 P60258 /update {wt=javabin&version=2} {add=[121 (1415260522873356288)]} 0 1
[junit4:junit4]   2> 15821 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15822 T371 C28 P60262 /update {wt=javabin&version=2} {add=[121]} 0 6
[junit4:junit4]   2> 15852 T353 C29 P60258 /update {wt=javabin&version=2} {add=[122 (1415260522888036352)]} 0 27
[junit4:junit4]   2> 15864 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15865 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 15866 T380 C30 P60279 /update {wt=javabin&version=2} {add=[122]} 0 10
[junit4:junit4]   2> 15872 T353 C29 P60258 /update {wt=javabin&version=2} {add=[123 (1415260522934173696)]} 0 3
[junit4:junit4]   2> 15881 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15882 T371 C28 P60262 /update {wt=javabin&version=2} {add=[123 (1415260522940465152)]} 0 7
[junit4:junit4]   2> 15887 T353 C29 P60258 /update {wt=javabin&version=2} {add=[124 (1415260522950950912)]} 0 2
[junit4:junit4]   2> 15896 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15897 T371 C28 P60262 /update {wt=javabin&version=2} {add=[124 (1415260522956193792)]} 0 7
[junit4:junit4]   2> 15902 T353 C29 P60258 /update {wt=javabin&version=2} {add=[125 (1415260522966679552)]} 0 2
[junit4:junit4]   2> 15911 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15912 T371 C28 P60262 /update {wt=javabin&version=2} {add=[125 (1415260522971922432)]} 0 7
[junit4:junit4]   2> 15917 T353 C29 P60258 /update {wt=javabin&version=2} {add=[126 (1415260522982408192)]} 0 2
[junit4:junit4]   2> 15925 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15926 T371 C28 P60262 /update {wt=javabin&version=2} {add=[126]} 0 6
[junit4:junit4]   2> 15931 T353 C29 P60258 /update {wt=javabin&version=2} {add=[127 (1415260522997088256)]} 0 2
[junit4:junit4]   2> 15960 T380 C30 P60279 /update {wt=javabin&version=2} {add=[127 (1415260523002331136)]} 0 25
[junit4:junit4]   2> 15965 T353 C29 P60258 /update {wt=javabin&version=2} {add=[128 (1415260523032739840)]} 0 1
[junit4:junit4]   2> 15972 T380 C30 P60279 /update {wt=javabin&version=2} {add=[128 (1415260523037982720)]} 0 3
[junit4:junit4]   2> 15977 T353 C29 P60258 /update {wt=javabin&version=2} {add=[129 (1415260523045322752)]} 0 2
[junit4:junit4]   2> 15986 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15986 T371 C28 P60262 /update {wt=javabin&version=2} {add=[129 (1415260523050565632)]} 0 6
[junit4:junit4]   2> 15991 T353 C29 P60258 /update {wt=javabin&version=2} {add=[130 (1415260523060002816)]} 0 2
[junit4:junit4]   2> 15998 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15998 T371 C28 P60262 /update {wt=javabin&version=2} {add=[130]} 0 5
[junit4:junit4]   2> 16002 T353 C29 P60258 /update {wt=javabin&version=2} {add=[131 (1415260523072585728)]} 0 1
[junit4:junit4]   2> 16010 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16010 T371 C28 P60262 /update {wt=javabin&version=2} {add=[131]} 0 5
[junit4:junit4]   2> 16038 T353 C29 P60258 /update {wt=javabin&version=2} {add=[132 (1415260523085168640)]} 0 25
[junit4:junit4]   2> 16050 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16050 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16051 T380 C30 P60279 /update {wt=javabin&version=2} {add=[132]} 0 10
[junit4:junit4]   2> 16057 T353 C29 P60258 /update {wt=javabin&version=2} {add=[133 (1415260523128160256)]} 0 3
[junit4:junit4]   2> 16069 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16069 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16070 T380 C30 P60279 /update {wt=javabin&version=2} {add=[133]} 0 10
[junit4:junit4]   2> 16074 T353 C29 P60258 /update {wt=javabin&version=2} {add=[134 (1415260523148083200)]} 0 1
[junit4:junit4]   2> 16078 T380 C30 P60279 /update {wt=javabin&version=2} {add=[134 (1415260523152277504)]} 0 1
[junit4:junit4]   2> 16083 T353 C29 P60258 /update {wt=javabin&version=2} {add=[135 (1415260523157520384)]} 0 1
[junit4:junit4]   2> 16092 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16092 T371 C28 P60262 /update {wt=javabin&version=2} {add=[135 (1415260523161714688)]} 0 6
[junit4:junit4]   2> 16097 T353 C29 P60258 /update {wt=javabin&version=2} {add=[136 (1415260523171151872)]} 0 2
[junit4:junit4]   2> 16105 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16105 T371 C28 P60262 /update {wt=javabin&version=2} {add=[136]} 0 5
[junit4:junit4]   2> 16110 T353 C29 P60258 /update {wt=javabin&version=2} {add=[137 (1415260523184783360)]} 0 2
[junit4:junit4]   2> 16115 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16116 T371 C28 P60262 /update {wt=javabin&version=2} {add=[137]} 0 5
[junit4:junit4]   2> 16121 T353 C29 P60258 /update {wt=javabin&version=2} {add=[138 (1415260523196317696)]} 0 2
[junit4:junit4]   2> 16195 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=25 
[junit4:junit4]   2> 16196 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=68 
[junit4:junit4]   2> 16197 T380 C30 P60279 /update {wt=javabin&version=2} {add=[138]} 0 73
[junit4:junit4]   2> 16203 T353 C29 P60258 /update {wt=javabin&version=2} {add=[139 (1415260523282300928)]} 0 3
[junit4:junit4]   2> 16208 T380 C30 P60279 /update {wt=javabin&version=2} {add=[139 (1415260523287543808)]} 0 2
[junit4:junit4]   2> 16212 T353 C29 P60258 /update {wt=javabin&version=2} {add=[140 (1415260523292786688)]} 0 1
[junit4:junit4]   2> 16219 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16220 T371 C28 P60262 /update {wt=javabin&version=2} {add=[140]} 0 5
[junit4:junit4]   2> 16224 T353 C29 P60258 /update {wt=javabin&version=2} {add=[141 (1415260523304321024)]} 0 1
[junit4:junit4]   2> 16231 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16232 T371 C28 P60262 /update {wt=javabin&version=2} {add=[141]} 0 5
[junit4:junit4]   2> 16264 T353 C29 P60258 /update {wt=javabin&version=2} {add=[142 (1415260523317952512)]} 0 28
[junit4:junit4]   2> 16295 T380 C30 P60279 /update {wt=javabin&version=2} {add=[142 (1415260523351506944)]} 0 27
[junit4:junit4]   2> 16301 T353 C29 P60258 /update {wt=javabin&version=2} {add=[143 (1415260523384012800)]} 0 3
[junit4:junit4]   2> 16308 T380 C30 P60279 /update {wt=javabin&version=2} {add=[143 (1415260523391352832)]} 0 3
[junit4:junit4]   2> 16312 T353 C29 P60258 /update {wt=javabin&version=2} {add=[144 (1415260523397644288)]} 0 1
[junit4:junit4]   2> 16316 T380 C30 P60279 /update {wt=javabin&version=2} {add=[144 (1415260523401838592)]} 0 1
[junit4:junit4]   2> 16321 T353 C29 P60258 /update {wt=javabin&version=2} {add=[145 (1415260523406032896)]} 0 2
[junit4:junit4]   2> 16328 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16328 T371 C28 P60262 /update {wt=javabin&version=2} {add=[145]} 0 5
[junit4:junit4]   2> 16333 T353 C29 P60258 /update {wt=javabin&version=2} {add=[146 (1415260523418615808)]} 0 2
[junit4:junit4]   2> 16348 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 16349 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 16350 T380 C30 P60279 /update {wt=javabin&version=2} {add=[146]} 0 14
[junit4:junit4]   2> 16355 T353 C29 P60258 /update {wt=javabin&version=2} {add=[147 (1415260523441684480)]} 0 2
[junit4:junit4]   2> 16362 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16363 T371 C28 P60262 /update {wt=javabin&version=2} {add=[147]} 0 5
[junit4:junit4]   2> 16368 T353 C29 P60258 /update {wt=javabin&version=2} {add=[148 (1415260523455315968)]} 0 2
[junit4:junit4]   2> 16380 T391 C27 P60283 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16380 T372 C28 P60262 REQ /update {distrib.from=http://127.0.0.1:60279/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16381 T380 C30 P60279 /update {wt=javabin&version=2} {add=[148]} 0 10
[junit4:junit4]   2> 16387 T353 C29 P60258 /update {wt=javabin&version=2} {add=[149 (1415260523475238912)]} 0 2
[junit4:junit4]   2> 16395 T379 C30 P60279 REQ /update {distrib.from=http://127.0.0.1:60262/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16396 T371 C28 P60262 /update {wt=javabin&version=2} {add=[149]} 0 6
[junit4:junit4]   2> 16399 T353 C29 P60258 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16667 T353 C29 P60258 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/control/data/index,segFN=segments_2,generation=2,filenames=[_5_nrm.cfe, _3_MockVariableIntBlock_0.frq, _4_MockSep_0.skp, _6_Asserting_0.frq, _6_Direct_0.tip, _6_Direct_0.tim, _3.fdt, _4_MockVariableIntBlock_0.tii, _2_MockSep_0.doc, _1_Asserting_0.tim, _1_Asserting_0.tip, _6_MockSep_0.doc, _5_MockSep_0.skp, _6_MockSep_0.frq, _6_MockVariableIntBlock_0.tii, _1_MockSep_0.pyl, _5_Direct_0.prx, _6_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.tii, _1_MockSep_0.tib, _5_MockSep_0.doc, _1_MockSep_0.tii, _1_MockVariableIntBlock_0.tib, _1.fdx, _4_MockVariableIntBlock_0.tib, _1.fdt, _3_MockSep_0.pos, _5_nrm.cfs, _0_MockVariableIntBlock_0.frq, _6.fdt, _2.si, _1_MockVariableIntBlock_0.skp, _0_MockVariableIntBlock_0.doc, _6_MockSep_0.pyl, _2_MockSep_0.skp, _6.fdx, _1_MockVariableIntBlock_0.pyl, _5_MockSep_0.tii, _3_MockVariableIntBlock_0.tii, _2_nrm.cfe, _0_MockVariableIntBlock_0.pos, _2_nrm.cfs, _4_MockVariableIntBlock_0.frq, _1_Direct_0.prx, _2.fnm, _5_MockVariableIntBlock_0.frq, _3.fdx, _2_MockSep_0.pyl, _1.si, _5_MockSep_0.tib, _0_Direct_0.tip, _0_Direct_0.prx, _3_MockSep_0.skp, _0_Direct_0.tim, _1_Direct_0.frq, _3_MockVariableIntBlock_0.pos, _3_MockSep_0.doc, _3_nrm.cfe, _5_MockVariableIntBlock_0.pyl, _4_nrm.cfe, _4.fdx, _4_Direct_0.frq, _3_Asserting_0.prx, _2_Asserting_0.prx, _1_Direct_0.tim, _6_MockVariableIntBlock_0.frq, _1_Direct_0.tip, _6_MockSep_0.tii, _1_MockSep_0.doc, _3_Direct_0.tip, _6_MockSep_0.tib, _3_MockVariableIntBlock_0.doc, _0_Direct_0.frq, _5.si, _3_nrm.cfs, _4_MockVariableIntBlock_0.pyl, _4_nrm.cfs, _3_MockVariableIntBlock_0.tib, _3_Direct_0.tim, _5_MockSep_0.pyl, _5_Asserting_0.prx, _5_MockVariableIntBlock_0.doc, _6_MockVariableIntBlock_0.pyl, _2_MockVariableIntBlock_0.frq, _6_Asserting_0.tim, _6_nrm.cfs, _6_Asserting_0.tip, _2_Asserting_0.frq, _5_Asserting_0.frq, _0_MockSep_0.doc, _1_nrm.cfs, _5.fnm, _6_nrm.cfe, _2_MockSep_0.frq, _4.fdt, _4.si, _1_nrm.cfe, _3_Asserting_0.frq, _2_Direct_0.prx, _0_MockSep_0.frq, _2_Direct_0.frq, _2_Direct_0.tip, _2_Direct_0.tim, _0_MockSep_0.pos, _4_MockSep_0.tib, _3_MockSep_0.frq, _4_MockSep_0.doc, _5_MockVariableIntBlock_0.tii, _4_Asserting_0.frq, _1_MockSep_0.skp, _4_MockVariableIntBlock_0.doc, _2.fdt, _2.fdx, _2_Asserting_0.tim, _0_Asserting_0.frq, _2_Asserting_0.tip, _4_MockSep_0.pyl, _3.si, _2_MockVariableIntBlock_0.skp, _4_MockSep_0.tii, _0_Asserting_0.tim, _5_Direct_0.tim, _2_MockVariableIntBlock_0.pyl, _0_Asserting_0.tip, _4_Asserting_0.tip, _3.fnm, _3_MockSep_0.pyl, _2_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pyl, _4_Asserting_0.prx, _4_Asserting_0.tim, _1_MockVariableIntBlock_0.doc, _6_MockSep_0.pos, _5_Direct_0.tip, _2_MockVariableIntBlock_0.tii, _1_MockSep_0.pos, _6_Asserting_0.prx, _5_MockVariableIntBlock_0.tib, _2_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.pos, _1_Asserting_0.frq, _6_MockSep_0.skp, _6_Direct_0.prx, _1_Asserting_0.prx, _3_MockVariableIntBlock_0.pyl, _6.si, _6.fnm, _3_Direct_0.frq, _5_MockSep_0.pos, _5.fdt, _4.fnm, _0_MockVariableIntBlock_0.skp, _1.fnm, _0_Asserting_0.prx, _0_MockSep_0.skp, _3_MockSep_0.tib, _3_Direct_0.prx, _6_MockVariableIntBlock_0.doc, _2_MockVariableIntBlock_0.pos, _0.fnm, _5_MockVariableIntBlock_0.pos, _4_MockSep_0.pos, _4_Direct_0.tim, _5_Asserting_0.tim, _5_MockSep_0.frq, _2_MockSep_0.tii, _5_Direct_0.frq, _2_MockSep_0.tib, _3_MockSep_0.tii, _4_Direct_0.prx, _4_Direct_0.tip, _5_Asserting_0.tip, _2_MockSep_0.pos, _1_MockSep_0.frq, _4_MockVariableIntBlock_0.skp, _0_MockVariableIntBlock_0.tib, _5_MockVariableIntBlock_0.skp, _4_MockVariableIntBlock_0.pos, _6_MockVariableIntBlock_0.pos, _0.si, _0_MockVariableIntBlock_0.tii, _0_nrm.cfs, _6_MockVariableIntBlock_0.skp, _0_MockSep_0.tib, _1_MockVariableIntBlock_0.frq, _3_Asserting_0.tim, _3_Asserting_0.tip, _0_nrm.cfe, segments_2, _4_MockSep_0.frq, _5.fdx, _0.fdx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _6_Direct_0.frq, _3_MockVariableIntBlock_0.skp, _0.fdt]
[junit4:junit4]   2> 16668 T353 C29 P60258 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 16682 T353 C29 P60258 oass.SolrIndexSearcher.<init> Opening Searcher@67f424e main
[junit4:junit4]   2> 16683 T353 C29 P60258 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16684 T364 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67f424e 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> 16685 T353 C29 P60258 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 286
[junit4:junit4]   2> 16689 T380 C30 P60279 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16856 T380 C30 P60279 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty2/index,segFN=segments_2,generation=2,filenames=[_2_Direct_0.tip, _2_Direct_0.tim, _0_MockSep_0.pos, _3_MockVariableIntBlock_0.frq, _3.fdt, _3_MockSep_0.frq, _2_MockSep_0.doc, _1_Asserting_0.tim, _1_Asserting_0.tip, _1_MockSep_0.pyl, _1_MockSep_0.skp, _1_MockVariableIntBlock_0.tii, _1_MockSep_0.tib, _2.fdt, _2.fdx, _1_MockSep_0.tii, _1_MockVariableIntBlock_0.tib, _1.fdx, _2_Asserting_0.tim, _0_Asserting_0.frq, _2_Asserting_0.tip, _3_MockSep_0.pos, _1.fdt, _0_MockVariableIntBlock_0.frq, _3.si, _2_MockVariableIntBlock_0.skp, _1_MockVariableIntBlock_0.skp, _0_Asserting_0.tim, _2.si, _2_MockVariableIntBlock_0.pyl, _0_Asserting_0.tip, _0_MockVariableIntBlock_0.doc, _2_MockSep_0.skp, _1_MockVariableIntBlock_0.pyl, _3.fnm, _2_MockVariableIntBlock_0.doc, _3_MockSep_0.pyl, _3_MockVariableIntBlock_0.tii, _0_MockVariableIntBlock_0.pyl, _2_nrm.cfe, _1_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _2_nrm.cfs, _2_MockVariableIntBlock_0.tii, _1_MockSep_0.pos, _2_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.pos, _1_Asserting_0.frq, _1_Direct_0.prx, _2.fnm, _3.fdx, _2_MockSep_0.pyl, _1_Asserting_0.prx, _1.si, _3_MockVariableIntBlock_0.pyl, _0_Direct_0.tip, _3_MockSep_0.skp, _0_Direct_0.prx, _0_Direct_0.tim, _3_MockVariableIntBlock_0.pos, _1_Direct_0.frq, _3_nrm.cfe, _3_MockSep_0.doc, _3_Direct_0.frq, _0_MockVariableIntBlock_0.skp, _1.fnm, _0_Asserting_0.prx, _0_MockSep_0.skp, _3_Asserting_0.prx, _3_MockSep_0.tib, _2_Asserting_0.prx, _3_Direct_0.prx, _1_Direct_0.tim, _1_Direct_0.tip, _2_MockVariableIntBlock_0.pos, _1_MockSep_0.doc, _3_Direct_0.tip, _0.fnm, _3_MockVariableIntBlock_0.doc, _2_MockSep_0.tii, _3_MockSep_0.tii, _0_Direct_0.frq, _2_MockSep_0.tib, _3_nrm.cfs, _3_MockVariableIntBlock_0.tib, _2_MockSep_0.pos, _3_Direct_0.tim, _1_MockSep_0.frq, _2_MockVariableIntBlock_0.frq, _0_MockVariableIntBlock_0.tib, _2_Asserting_0.frq, _0_MockSep_0.doc, _0.si, _0_MockVariableIntBlock_0.tii, _1_nrm.cfs, _2_MockSep_0.frq, _0_nrm.cfs, _1_nrm.cfe, _3_Asserting_0.frq, _0_MockSep_0.tib, _1_MockVariableIntBlock_0.frq, _2_Direct_0.prx, _3_Asserting_0.tim, _3_Asserting_0.tip, _0_nrm.cfe, segments_2, _0_MockSep_0.frq, _0.fdx, _2_Direct_0.frq, _0_MockSep_0.tii, _0_MockSep_0.pyl, _3_MockVariableIntBlock_0.skp, _0.fdt]
[junit4:junit4]   2> 16857 T380 C30 P60279 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 16870 T380 C30 P60279 oass.SolrIndexSearcher.<init> Opening Searcher@3527f4ee main
[junit4:junit4]   2> 16871 T380 C30 P60279 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16873 T388 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3527f4ee main{StandardDirectoryReader(segments_2:9:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10 _3(5.0):C5)}
[junit4:junit4]   2> 16874 T380 C30 P60279 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:60262/solr/collection1/, StdNode: http://127.0.0.1:60283/solr/collection1/]
[junit4:junit4]   2> 16877 T372 C28 P60262 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16878 T391 C27 P60283 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 17068 T372 C28 P60262 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty1/index,segFN=segments_2,generation=2,filenames=[_2_Direct_0.tip, _2_Direct_0.tim, _0_MockSep_0.pos, _3_MockVariableIntBlock_0.frq, _3.fdt, _3_MockSep_0.frq, _2_MockSep_0.doc, _1_Asserting_0.tim, _1_Asserting_0.tip, _1_MockSep_0.pyl, _1_MockSep_0.skp, _1_MockVariableIntBlock_0.tii, _1_MockSep_0.tib, _2.fdt, _2.fdx, _1_MockSep_0.tii, _1_MockVariableIntBlock_0.tib, _1.fdx, _2_Asserting_0.tim, _0_Asserting_0.frq, _2_Asserting_0.tip, _3_MockSep_0.pos, _1.fdt, _0_MockVariableIntBlock_0.frq, _3.si, _2_MockVariableIntBlock_0.skp, _1_MockVariableIntBlock_0.skp, _0_Asserting_0.tim, _2.si, _2_MockVariableIntBlock_0.pyl, _0_Asserting_0.tip, _0_MockVariableIntBlock_0.doc, _2_MockSep_0.skp, _1_MockVariableIntBlock_0.pyl, _3.fnm, _2_MockVariableIntBlock_0.doc, _3_MockSep_0.pyl, _3_MockVariableIntBlock_0.tii, _0_MockVariableIntBlock_0.pyl, _2_nrm.cfe, _1_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _2_nrm.cfs, _2_MockVariableIntBlock_0.tii, _1_MockSep_0.pos, _2_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.pos, _1_Asserting_0.frq, _1_Direct_0.prx, _2.fnm, _3.fdx, _2_MockSep_0.pyl, _1_Asserting_0.prx, _1.si, _3_MockVariableIntBlock_0.pyl, _0_Direct_0.tip, _3_MockSep_0.skp, _0_Direct_0.prx, _0_Direct_0.tim, _3_MockVariableIntBlock_0.pos, _1_Direct_0.frq, _3_nrm.cfe, _3_MockSep_0.doc, _3_Direct_0.frq, _0_MockVariableIntBlock_0.skp, _1.fnm, _0_Asserting_0.prx, _0_MockSep_0.skp, _3_Asserting_0.prx, _3_MockSep_0.tib, _2_Asserting_0.prx, _3_Direct_0.prx, _1_Direct_0.tim, _1_Direct_0.tip, _2_MockVariableIntBlock_0.pos, _1_MockSep_0.doc, _3_Direct_0.tip, _0.fnm, _3_MockVariableIntBlock_0.doc, _2_MockSep_0.tii, _3_MockSep_0.tii, _0_Direct_0.frq, _2_MockSep_0.tib, _3_nrm.cfs, _3_MockVariableIntBlock_0.tib, _2_MockSep_0.pos, _3_Direct_0.tim, _1_MockSep_0.frq, _2_MockVariableIntBlock_0.frq, _0_MockVariableIntBlock_0.tib, _2_Asserting_0.frq, _0_MockSep_0.doc, _0.si, _0_MockVariableIntBlock_0.tii, _1_nrm.cfs, _2_MockSep_0.frq, _0_nrm.cfs, _1_nrm.cfe, _3_Asserting_0.frq, _0_MockSep_0.tib, _1_MockVariableIntBlock_0.frq, _2_Direct_0.prx, _3_Asserting_0.tim, _3_Asserting_0.tip, _0_nrm.cfe, segments_2, _0_MockSep_0.frq, _0.fdx, _2_Direct_0.frq, _0_MockSep_0.tii, _0_MockSep_0.pyl, _3_MockVariableIntBlock_0.skp, _0.fdt]
[junit4:junit4]   2> 17069 T372 C28 P60262 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 17075 T391 C27 P60283 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty3/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1349697595881/jetty3/index,segFN=segments_3,generation=3,filenames=[_2_Direct_0.tip, _2_Direct_0.tim, _0_MockSep_0.pos, _3_MockVariableIntBlock_0.frq, _3.fdt, _3_MockSep_0.frq, _2_MockSep_0.doc, _1_Asserting_0.tim, _1_Asserting_0.tip, _1_MockSep_0.pyl, _1_MockSep_0.skp, _1_MockVariableIntBlock_0.tii, _1_MockSep_0.tib, _2.fdt, _2.fdx, _1_MockSep_0.tii, _1_MockVariableIntBlock_0.tib, _1.fdx, _2_Asserting_0.tim, _0_Asserting_0.frq, _2_Asserting_0.tip, _3_MockSep_0.pos, _1.fdt, _0_MockVariableIntBlock_0.frq, _3.si, _2_MockVariableIntBlock_0.skp, _1_MockVariableIntBlock_0.skp, _0_Asserting_0.tim, _2.si, _2_MockVariableIntBlock_0.pyl, _0_Asserting_0.tip, _0_MockVariableIntBlock_0.doc, _2_MockSep_0.skp, _1_MockVariableIntBlock_0.pyl, _3.fnm, _2_MockVariableIntBlock_0.doc, _3_MockSep_0.pyl, _3_MockVariableIntBlock_0.tii, _0_MockVariableIntBlock_0.pyl, _2_nrm.cfe, _1_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _2_nrm.cfs, _2_MockVariableIntBlock_0.tii, _1_MockSep_0.pos, _2_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.pos, _1_Asserting_0.frq, _1_Direct_0.prx, _2.fnm, _3.fdx, _2_MockSep_0.pyl, _1_Asserting_0.prx, _1.si, _3_MockVariableIntBlock_0.pyl, _0_Direct_0.tip, _3_MockSep_0.skp, _0_Direct_0.prx, _0_Direct_0.tim, _3_MockVariableIntBlock_0.pos, _1_Direct_0.frq, _3_nrm.cfe, _3_MockSep_0.doc, _3_Direct_0.frq, _0_MockVariableIntBlock_0.skp, _1.fnm, _0_Asserting_0.prx, _0_MockSep_0.skp, _3_Asserting_0.prx, _3_MockSep_0.tib, _2_Asserting_0.prx, _3_Direct_0.prx, _1_Direct_0.tim, _1_Direct_0.tip, _2_MockVariableIntBlock_0.pos, _1_MockSep_0.doc, _3_Direct_0.tip, _0.fnm, _3_MockVariableIntBlock_0.doc, _2_MockSep_0.tii, _3_MockSep_0.tii, _0_Direct_0.frq, _2_MockSep_0.tib, _3_nrm.cfs, _3_MockVariableIntBlock_0.tib, _2_MockSep_0.pos, _3_Direct_0.tim, _1_MockSep_0.frq, _2_MockVariableIntBlock_0.frq, _0_MockVariableIntBlock_0.tib, _2_Asserting_0.frq, _0_MockSep_0.doc, _0.si, _0_MockVariableIntBlock_0.tii, _1_nrm.cfs, _2_MockSep_0.frq, _0_nrm.cfs, _1_nrm.cfe, _3_Asserting_0.frq, _0_MockSep_0.tib, _1_MockVariableIntBlock_0.frq, _2_Direct_0.prx, _3_Asserting_0.tim, _3_Asserting_0.tip, _0_nrm.cfe, _0_MockSep_0.frq, _0.fdx, _2_Direct_0.frq, _0_MockSep_0.tii, _0_MockSep_0.pyl, _3_MockVariableIntBlock_0.skp, _0.fdt, segments_3]
[junit4:junit4]   2> 17076 T391 C27 P60283 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 17089 T372 C28 P60262 oass.SolrIndexSearcher.<init> Opening Searcher@173345db main
[junit4:junit4]   2> 17090 T372 C28 P60262 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 17092 T376 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@173345db main{StandardDirectoryReader(segments_2:9:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10 _3(5.0):C2)}
[junit4:junit4]   2> 17092 T372 C28 P60262 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 215
[junit4:junit4]   2> 17094 T391 C27 P60283 oass.SolrIndexSearcher.<init> Opening Searcher@65c1866e main
[junit4:junit4]   2> 17095 T391 C27 P60283 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 17097 T400 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@65c1866e main{StandardDirectoryReader(segments_3:10:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10 _3(5.0):C2)}
[junit4:junit4]   2> 17098 T391 C27 P60283 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 220
[junit4:junit4]   2> 17099 T380 C30 P60279 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 410
[junit4:junit4]   2> 17122 T353 C29 P60258 REQ /select {distrib=false&sort=n_ti1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=3 
[junit4:junit4]   2> 17127 T340 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 17140 T340 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60254/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@42a24242
[junit4:junit4]   2> 17141 T411 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:60254
[junit4:junit4]   2> 17141 T340 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 17142 T411 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:60254, initiating session
[junit4:junit4]   2> 17142 T342 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:60301
[junit4:junit4]   2> 17143 T342 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:60301
[junit4:junit4]   2> 17146 T344 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a403ee9f00008 with negotiated timeout 10000 for client /140.211.11.196:60301
[junit4:junit4]   2> 17146 T411 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:60254, sessionid = 0x13a403ee9f00008, negotiated timeout = 10000
[junit4:junit4]   2> 17146 T412 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@42a24242 name:ZooKeeperConnection Watcher:127.0.0.1:60254/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 17147 T340 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 17148 T340 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 17164 T373 C28 P60262 REQ /select {sort=n_ti1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1349697613033&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 17164 T382 C30 P60279 REQ /select {sort=n_ti1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1349697613033&shard.url=127.0.0.1:60279/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 17196 T382 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613033&q=*:*&ids=126,127&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17198 T373 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613033&q=*:*&ids=10,1,114,122,110,124,16,129&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 17202 T381 C30 P60279 REQ /select {collection=collection1&sort=n_ti1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=45 
[junit4:junit4]   2> 17213 T353 C29 P60258 REQ /select {distrib=false&sort=n_ti1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17223 T382 C30 P60279 REQ /select {sort=n_ti1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613095&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 17224 T392 C27 P60283 REQ /select {sort=n_ti1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613095&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 17231 T382 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613095&q=*:*&ids=2,115,147,6,149,106,104&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17231 T392 C27 P60283 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613095&q=*:*&ids=138,107,102&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17234 T381 C30 P60279 REQ /select {sort=n_ti1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 17243 T353 C29 P60258 REQ /select {distrib=false&sort=n_f1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=3 
[junit4:junit4]   2> 17255 T383 C30 P60279 REQ /select {sort=n_f1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613125&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 17256 T369 C28 P60262 REQ /select {sort=n_f1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613125&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 17264 T383 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613125&q=*:*&ids=3,126,9&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17266 T369 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613125&q=*:*&ids=125,1,15,13,129,101,14&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17270 T367 C28 P60262 REQ /select {sort=n_f1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=21 
[junit4:junit4]   2> 17277 T353 C29 P60258 REQ /select {distrib=false&sort=n_f1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17287 T373 C28 P60262 REQ /select {sort=n_f1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613158&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17287 T382 C30 P60279 REQ /select {sort=n_f1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613158&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17293 T382 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613158&q=*:*&ids=149,5,105&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17295 T373 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613158&q=*:*&ids=114,133,138,148,8,102,11&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17297 T381 C30 P60279 REQ /select {collection=collection1&sort=n_f1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 17305 T353 C29 P60258 REQ /select {distrib=false&sort=n_tf1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 17316 T393 C27 P60283 REQ /select {sort=n_tf1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1349697613187&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17316 T383 C30 P60279 REQ /select {sort=n_tf1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1349697613187&shard.url=127.0.0.1:60279/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17322 T393 C27 P60283 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613187&q=*:*&ids=138,107,15,102,100&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17322 T383 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613187&q=*:*&ids=143,144,139,131,141&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17326 T367 C28 P60262 REQ /select {collection=collection1&sort=n_tf1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 17333 T353 C29 P60258 REQ /select {distrib=false&sort=n_tf1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17342 T383 C30 P60279 REQ /select {sort=n_tf1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613214&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 17343 T393 C27 P60283 REQ /select {sort=n_tf1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613214&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17348 T383 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613214&q=*:*&ids=3,103,130&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 17350 T393 C27 P60283 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613214&q=*:*&ids=125,1,148,110,111,4,14&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17353 T367 C28 P60262 REQ /select {collection=collection1&sort=n_tf1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 17361 T353 C29 P60258 REQ /select {distrib=false&sort=n_d1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 17371 T373 C28 P60262 REQ /select {sort=n_d1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613242&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17372 T382 C30 P60279 REQ /select {sort=n_d1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613242&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 17378 T382 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613242&q=*:*&ids=147,7,9,130,119&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17379 T373 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613242&q=*:*&ids=122,124,107,13,102&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17381 T380 C30 P60279 REQ /select {sort=n_d1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 17388 T353 C29 P60258 REQ /select {distrib=false&sort=n_d1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17399 T383 C30 P60279 REQ /select {sort=n_d1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613270&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17400 T393 C27 P60283 REQ /select {sort=n_d1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613270&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17407 T383 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613270&q=*:*&ids=3,127,136,103,131&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17408 T393 C27 P60283 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613270&q=*:*&ids=125,132,10,108,8&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17411 T367 C28 P60262 REQ /select {collection=collection1&sort=n_d1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=17 
[junit4:junit4]   2> 17418 T353 C29 P60258 REQ /select {distrib=false&sort=n_td1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 17428 T373 C28 P60262 REQ /select {sort=n_td1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613299&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17429 T382 C30 P60279 REQ /select {sort=n_td1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613299&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 17436 T382 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613299&q=*:*&ids=116,112,6,104&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17436 T373 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613299&q=*:*&ids=1,113,4,102,100,11&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17438 T380 C30 P60279 REQ /select {collection=collection1&sort=n_td1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 17445 T353 C29 P60258 REQ /select {distrib=false&sort=n_td1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17454 T369 C28 P60262 REQ /select {sort=n_td1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1349697613326&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 17455 T383 C30 P60279 REQ /select {sort=n_td1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613326&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17460 T383 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613326&q=*:*&ids=128,136,106,103,142&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17461 T369 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613326&q=*:*&ids=133,148,122,107,8&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17464 T367 C28 P60262 REQ /select {collection=collection1&sort=n_td1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=14 
[junit4:junit4]   2> 17472 T353 C29 P60258 REQ /select {distrib=false&sort=n_l1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 17482 T419 C28 P60262 REQ /select {sort=n_l1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613353&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17482 T384 C30 P60279 REQ /select {sort=n_l1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613353&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17488 T384 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613353&q=*:*&ids=116,117,115,112,147,139,130&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17488 T419 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613353&q=*:*&ids=114,124,118&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 17492 T394 C27 P60283 REQ /select {sort=n_l1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 17498 T353 C29 P60258 REQ /select {distrib=false&sort=n_l1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17507 T383 C30 P60279 REQ /select {sort=n_l1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613379&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 17508 T393 C27 P60283 REQ /select {sort=n_l1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613379&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17514 T383 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613379&q=*:*&ids=104,140&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17515 T393 C27 P60283 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613379&q=*:*&ids=125,135,108,15,8,13,101,14&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17517 T371 C28 P60262 REQ /select {sort=n_l1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=14 
[junit4:junit4]   2> 17526 T353 C29 P60258 REQ /select {distrib=false&sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 17537 T392 C27 P60283 REQ /select {sort=n_tl1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1349697613407&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 17537 T382 C30 P60279 REQ /select {sort=n_tl1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613407&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 17543 T382 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613407&q=*:*&ids=117,126,6&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17543 T392 C27 P60283 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613407&q=*:*&ids=114,113,108,8,14,11,12&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17546 T380 C30 P60279 REQ /select {collection=collection1&sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 17552 T353 C29 P60258 REQ /select {distrib=false&sort=n_tl1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17561 T392 C27 P60283 REQ /select {sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613432&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17561 T382 C30 P60279 REQ /select {sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613432&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17567 T392 C27 P60283 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613432&q=*:*&ids=125,138,122&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17567 T382 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613432&q=*:*&ids=143,3,134,149,5,120,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17569 T381 C30 P60279 REQ /select {sort=n_tl1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=13 
[junit4:junit4]   2> 17576 T353 C29 P60258 REQ /select {distrib=false&sort=n_dt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 17585 T392 C27 P60283 REQ /select {sort=n_dt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613456&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17586 T382 C30 P60279 REQ /select {sort=n_dt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613456&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 17591 T392 C27 P60283 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613456&q=*:*&ids=135,114,146,108&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17592 T382 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613456&q=*:*&ids=3,136,106,104,140,131&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17594 T381 C30 P60279 REQ /select {sort=n_dt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=14 
[junit4:junit4]   2> 17601 T353 C29 P60258 REQ /select {distrib=false&sort=n_dt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17609 T384 C30 P60279 REQ /select {sort=n_dt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613481&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 17610 T419 C28 P60262 REQ /select {sort=n_dt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613481&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17616 T419 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613481&q=*:*&ids=113,110,124,4,14&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17616 T384 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613481&q=*:*&ids=145,127,128,6,139&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17618 T394 C27 P60283 REQ /select {sort=n_dt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=13 
[junit4:junit4]   2> 17628 T353 C29 P60258 REQ /select {distrib=false&sort=n_tdt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 17637 T373 C28 P60262 REQ /select {sort=n_tdt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613508&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17638 T382 C30 P60279 REQ /select {sort=n_tdt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613508&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 17644 T373 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613508&q=*:*&ids=135,109,4,124,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17645 T382 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613508&q=*:*&ids=144,145,7,121,140&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17647 T380 C30 P60279 REQ /select {sort=n_tdt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 17657 T353 C29 P60258 REQ /select {distrib=false&sort=n_tdt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17666 T395 C27 P60283 REQ /select {sort=n_tdt1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1349697613537&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 17666 T384 C30 P60279 REQ /select {sort=n_tdt1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1349697613537&shard.url=127.0.0.1:60279/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17670 T384 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613537&q=*:*&ids=143,2,5,104&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17672 T395 C27 P60283 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613537&q=*:*&ids=113,122,8,102,129,11&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17674 T394 C27 P60283 REQ /select {collection=collection1&sort=n_tdt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=13 
[junit4:junit4]   2> 17720 T353 C29 P60258 REQ /select {distrib=false&sort=a_si+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=37 
[junit4:junit4]   2> 17730 T373 C28 P60262 REQ /select {sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613601&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17730 T382 C30 P60279 REQ /select {sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613601&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17736 T373 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613601&q=*:*&ids=10,1,8,13,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17737 T382 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613601&q=*:*&ids=3,2,7,5,9&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17739 T380 C30 P60279 REQ /select {sort=a_si+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=14 
[junit4:junit4]   2> 17769 T353 C29 P60258 REQ /select {distrib=false&sort=a_si+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=21 
[junit4:junit4]   2> 17778 T369 C28 P60262 REQ /select {sort=a_si+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613650&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 17779 T383 C30 P60279 REQ /select {sort=a_si+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613650&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17793 T369 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613650&q=*:*&ids=1,4,13,11,12&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17793 T383 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613650&q=*:*&ids=3,2,7,6,9&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17796 T371 C28 P60262 REQ /select {collection=collection1&sort=a_si+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=22 
[junit4:junit4]   2> 17808 T353 C29 P60258 REQ /select {sort=a_si+desc&fl=*,score&q=*:*&distrib=false&wt=javabin&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17817 T419 C28 P60262 REQ /select {sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613689&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 17818 T384 C30 P60279 REQ /select {sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613689&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17824 T419 C28 P60262 REQ /select {fl=*,score&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613689&q=*:*&ids=10,1,8,13,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17824 T384 C30 P60279 REQ /select {fl=*,score&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613689&q=*:*&ids=3,2,7,5,9&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17826 T396 C27 P60283 REQ /select {sort=a_si+desc&fl=*,score&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=13 
[junit4:junit4]   2> 17836 T353 C29 P60258 REQ /select {sort=n_tl1+asc&fl=*,score&q=*:*&distrib=false&wt=javabin&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17844 T393 C27 P60283 REQ /select {sort=n_tl1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613716&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 17845 T383 C30 P60279 REQ /select {sort=n_tl1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613716&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17851 T393 C27 P60283 REQ /select {fl=*,score&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613716&q=*:*&ids=125,138,122&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17852 T383 C30 P60279 REQ /select {fl=*,score&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613716&q=*:*&ids=143,3,134,149,5,120,103&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17853 T371 C28 P60262 REQ /select {sort=n_tl1+asc&fl=*,score&q=*:*&collection=collection1&wt=javabin&version=2} hits=67 status=0 QTime=13 
[junit4:junit4]   2> 17858 T353 C29 P60258 REQ /select {distrib=false&sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 17867 T393 C27 P60283 REQ /select {sort=n_tl1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613740&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 17868 T383 C30 P60279 REQ /select {sort=n_tl1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613740&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17874 T383 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613740&q=*:*&ids=117,126,6&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 17874 T393 C27 P60283 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613740&q=*:*&ids=114,113,108,8,14,11,12&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17876 T371 C28 P60262 REQ /select {collection=collection1&sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=12 
[junit4:junit4]   2> 17882 T353 C29 P60258 REQ /select {distrib=false&wt=javabin&q={!func}a_si&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17890 T369 C28 P60262 REQ /select {distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613762&start=0&q={!func}a_si&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17890 T383 C30 P60279 REQ /select {distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613762&start=0&q={!func}a_si&isShard=true&fsv=true} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 17904 T369 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613762&q={!func}a_si&ids=10,1,8,13,12&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17904 T383 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613762&q={!func}a_si&ids=3,2,7,5,9&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17906 T371 C28 P60262 REQ /select {collection=collection1&wt=javabin&q={!func}a_si&version=2} hits=67 status=0 QTime=20 
[junit4:junit4]   2> 17911 T353 C29 P60258 REQ /select {distrib=false&fl=*,score&wt=javabin&q={!func}a_si&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17918 T369 C28 P60262 REQ /select {fl=id,score&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613790&start=0&q={!func}a_si&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17918 T383 C30 P60279 REQ /select {fl=id,score&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613790&start=0&q={!func}a_si&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 17923 T383 C30 P60279 REQ /select {fl=*,score&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613790&q={!func}a_si&ids=3,2,7,5,9&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17923 T369 C28 P60262 REQ /select {fl=*,score&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613790&q={!func}a_si&ids=10,1,8,13,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17924 T367 C28 P60262 REQ /select {fl=*,score&wt=javabin&q={!func}a_si&version=2} hits=67 status=0 QTime=10 
[junit4:junit4]   2> 17930 T353 C29 P60258 REQ /select {distrib=false&wt=javabin&q=quick&version=2} hits=2 status=0 QTime=1 
[junit4:junit4]   2> 17937 T382 C30 P60279 REQ /select {distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613809&start=0&q=quick&isShard=true&fsv=true} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 17938 T373 C28 P60262 REQ /select {distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613809&start=0&q=quick&isShard=true&fsv=true} hits=1 status=0 QTime=1 
[junit4:junit4]   2> 17942 T382 C30 P60279 REQ /select {shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613809&q=quick&ids=5&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17942 T373 C28 P60262 REQ /select {shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613809&q=quick&ids=4&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17943 T380 C30 P60279 REQ /select {collection=collection1&wt=javabin&q=quick&version=2} hits=2 status=0 QTime=10 
[junit4:junit4]   2> 17947 T353 C29 P60258 REQ /select {fl=id&start=0&q=all&distrib=false&wt=javabin&version=2} hits=2 status=0 QTime=1 
[junit4:junit4]   2> 17953 T382 C30 P60279 REQ /select {fl=id,score&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613825&start=0&q=all&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 17954 T392 C27 P60283 REQ /select {fl=id,score&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613825&start=0&q=all&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=2 status=0 QTime=1 
[junit4:junit4]   2> 17958 T392 C27 P60283 REQ /select {fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613825&start=0&q=all&ids=1,8&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17959 T381 C30 P60279 REQ /select {fl=id&wt=javabin&start=0&q=all&version=2} hits=2 status=0 QTime=10 
[junit4:junit4]   2> 17962 T353 C29 P60258 REQ /select {fl=foofoofoo&start=0&q=all&distrib=false&wt=javabin&version=2} hits=2 status=0 QTime=0 
[junit4:junit4]   2> 17969 T382 C30 P60279 REQ /select {fl=id,score&shard.url=127.0.0.1:60279/solr/collection1/&NOW=1349697613841&start=0&q=all&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 17969 T392 C27 P60283 REQ /select {fl=id,score&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613841&start=0&q=all&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=2 status=0 QTime=0 
[junit4:junit4]   2> 17973 T392 C27 P60283 REQ /select {fl=foofoofoo&fl=id&shard.url=127.0.0.1:60262/solr/collection1/|127.0.0.1:60283/solr/collection1/&NOW=1349697613841&start=0&q=all&ids=1,8&distrib=fals

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

ge:Server Error
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([F08D6FB55DC19F4A:716BE1AD2A9EFF76]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:372)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZkTest.waitForNon403or404or503(BasicDistributedZkTest.java:549)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZkTest.testCollectionsAPI(BasicDistributedZkTest.java:386)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:331)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java: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$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 577882 T340 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {n_f1=MockVariableIntBlock(baseBlockSize=118), foo_b=PostingsFormat(name=Direct), cat=PostingsFormat(name=Asserting), foo_d=PostingsFormat(name=MockSep), foo_f=PostingsFormat(name=Direct), n_tl1=MockVariableIntBlock(baseBlockSize=118), n_d1=PostingsFormat(name=Asserting), rnd_b=PostingsFormat(name=MockSep), intDefault=PostingsFormat(name=Asserting), n_td1=MockVariableIntBlock(baseBlockSize=118), timestamp=PostingsFormat(name=Asserting), id=PostingsFormat(name=MockSep), range_facet_sl=PostingsFormat(name=Direct), range_facet_si=MockVariableIntBlock(baseBlockSize=118), oddField_s=PostingsFormat(name=Direct), sequence_i=PostingsFormat(name=Direct), name=PostingsFormat(name=MockSep), foo_i=MockVariableIntBlock(baseBlockSize=118), regex_dup_B_s=PostingsFormat(name=Asserting), multiDefault=MockVariableIntBlock(baseBlockSize=118), n_tf1=PostingsFormat(name=Asserting), n_dt1=MockVariableIntBlock(baseBlockSize=118), genre_s=PostingsFormat(name=Direct), author_t=MockVariableIntBlock(baseBlockSize=118), n_ti1=PostingsFormat(name=MockSep), range_facet_l=PostingsFormat(name=Asserting), text=PostingsFormat(name=Direct), _version_=PostingsFormat(name=Asserting), val_i=PostingsFormat(name=MockSep), SubjectTerms_mfacet=PostingsFormat(name=Asserting), series_t=MockVariableIntBlock(baseBlockSize=118), a_t=PostingsFormat(name=Asserting), n_tdt1=PostingsFormat(name=Asserting), regex_dup_A_s=PostingsFormat(name=Direct), price=PostingsFormat(name=Direct), other_tl1=PostingsFormat(name=Asserting), n_l1=PostingsFormat(name=Asserting), a_si=MockVariableIntBlock(baseBlockSize=118), inStock=PostingsFormat(name=Direct)}, sim=DefaultSimilarity, locale=da, timezone=Australia/Queensland
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Oracle Corporation 1.7.0_06 (64-bit)/cpus=16,threads=1,free=86259904,total=491782144
[junit4:junit4]   2> NOTE: All tests run in this JVM: [PluginInfoTest, CircularListTest, HighlighterConfigTest, BinaryUpdateRequestHandlerTest, MBeansHandlerTest, SolrRequestParserTest, TestFastLRUCache, TestIndexSearcher, SimpleFacetsTest, TestPerFieldSimilarity, CoreAdminHandlerTest, WordBreakSolrSpellCheckerTest, NumericFieldsTest, SearchHandlerTest, NotRequiredUniqueKeyTest, SortByFunctionTest, TestStressVersions, TestRangeQuery, TestSearchPerf, MinimalSchemaTest, DirectUpdateHandlerOptimizeTest, CoreContainerCoreInitFailuresTest, SOLR749Test, LoggingHandlerTest, SpatialFilterTest, TestLuceneMatchVersion, UniqFieldsUpdateProcessorFactoryTest, CopyFieldTest, MoreLikeThisHandlerTest, BadComponentTest, AlternateDirectoryTest, DefaultValueUpdateProcessorTest, TestWordDelimiterFilterFactory, FastVectorHighlighterTest, JsonLoaderTest, DateFieldTest, TestDefaultSimilarityFactory, TestJmxMonitoredMap, JSONWriterTest, SpellCheckComponentTest, TestSystemIdResolver, TestLMJelinekMercerSimilarityFactory, TestSolr4Spatial, ZkControllerTest, TestJoin, SuggesterFSTTest, TestSolrJ, TestSuggestSpellingConverter, TestXIncludeConfig, ResponseLogComponentTest, TestQueryTypes, BasicDistributedZkTest]
[junit4:junit4] Completed on J0 in 578.25s, 1 test, 1 error <<< FAILURES!

[...truncated 342 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:38: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build.xml:180: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/module-build.xml:61: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:1143: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:816: There were test failures: 230 suites, 956 tests, 1 error, 21 ignored (3 assumptions)

Total time: 33 minutes 10 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message