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 # 3173 - Failure
Date Fri, 07 Sep 2012 08:04:35 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-java7/3173/

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

Error Message:
some core start times did not change on reload

Stack Trace:
java.lang.AssertionError: some core start times did not change on reload
	at __randomizedtesting.SeedInfo.seed([43C493D765ED4C39:C2221DCF12B22C05]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.apache.solr.cloud.BasicDistributedZkTest.testCollectionsAPI(BasicDistributedZkTest.java:409)
	at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:313)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:691)
	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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 7798 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 3 T796 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-1347004331351
[junit4:junit4]   2> 4 T796 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 7 T797 oazs.ZooKeeperServer.setTickTime tickTime set to 3000
[junit4:junit4]   2> 8 T797 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 8 T797 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 105 T796 oasc.ZkTestServer.run start zk server on port:54312
[junit4:junit4]   2> 105 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54312 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@5cdfb8a7
[junit4:junit4]   2> 106 T802 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54312
[junit4:junit4]   2> 106 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 108 T802 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:54312, initiating session
[junit4:junit4]   2> 108 T798 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:59632
[junit4:junit4]   2> 109 T798 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:59632
[junit4:junit4]   2> 109 T800 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 111 T800 oazs.NIOServerCnxn.finishSessionInit Established session 0x1399fb6f55c0000 with negotiated timeout 10000 for client /140.211.11.196:59632
[junit4:junit4]   2> 111 T802 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:54312, sessionid = 0x1399fb6f55c0000, negotiated timeout = 10000
[junit4:junit4]   2> 112 T803 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5cdfb8a7 name:ZooKeeperConnection Watcher:127.0.0.1:54312 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 112 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 112 T796 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 117 T801 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x1399fb6f55c0000
[junit4:junit4]   2> 138 T803 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 138 T796 oaz.ZooKeeper.close Session: 0x1399fb6f55c0000 closed
[junit4:junit4]   2> 139 T798 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:59632 which had sessionid 0x1399fb6f55c0000
[junit4:junit4]   2> 139 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54312/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@5d7dd3f8
[junit4:junit4]   2> 140 T804 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54312
[junit4:junit4]   2> 140 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 140 T804 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:54312, initiating session
[junit4:junit4]   2> 141 T798 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:36208
[junit4:junit4]   2> 141 T798 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:36208
[junit4:junit4]   2> 142 T800 oazs.NIOServerCnxn.finishSessionInit Established session 0x1399fb6f55c0001 with negotiated timeout 10000 for client /140.211.11.196:36208
[junit4:junit4]   2> 142 T804 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:54312, sessionid = 0x1399fb6f55c0001, negotiated timeout = 10000
[junit4:junit4]   2> 143 T805 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5d7dd3f8 name:ZooKeeperConnection Watcher:127.0.0.1:54312/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 143 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 143 T796 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 147 T796 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 191 T796 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 232 T796 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 235 T796 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> 236 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 273 T796 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> 273 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 380 T796 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> 381 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 390 T796 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> 390 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 396 T796 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> 397 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 401 T796 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> 402 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 405 T796 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> 406 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 409 T796 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> 410 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 413 T796 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> 413 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 416 T796 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> 417 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 424 T801 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x1399fb6f55c0001
[junit4:junit4]   2> 425 T805 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 425 T796 oaz.ZooKeeper.close Session: 0x1399fb6f55c0001 closed
[junit4:junit4]   2> 426 T798 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:36208 which had sessionid 0x1399fb6f55c0001
[junit4:junit4]   2> 571 T796 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 576 T796 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:42631
[junit4:junit4]   2> 576 T796 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 577 T796 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-files/control1
[junit4:junit4]   2> 577 T796 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-files/control1/'
[junit4:junit4]   2> 619 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 620 T796 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 620 T796 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-files/control1
[junit4:junit4]   2> 621 T796 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/solr.xml
[junit4:junit4]   2> 621 T796 oasc.CoreContainer.<init> New CoreContainer 1501616734
[junit4:junit4]   2> 622 T796 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/'
[junit4:junit4]   2> 622 T796 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/'
[junit4:junit4]   2> 654 T796 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 670 T796 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54312/solr
[junit4:junit4]   2> 670 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54312/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@2855b364
[junit4:junit4]   2> 671 T815 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54312
[junit4:junit4]   2> 671 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 673 T815 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:54312, initiating session
[junit4:junit4]   2> 673 T798 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:34420
[junit4:junit4]   2> 673 T798 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:34420
[junit4:junit4]   2> 675 T800 oazs.NIOServerCnxn.finishSessionInit Established session 0x1399fb6f55c0002 with negotiated timeout 8000 for client /140.211.11.196:34420
[junit4:junit4]   2> 675 T815 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:54312, sessionid = 0x1399fb6f55c0002, negotiated timeout = 8000
[junit4:junit4]   2> 675 T816 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2855b364 name:ZooKeeperConnection Watcher:127.0.0.1:54312/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 676 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 678 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 691 T796 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 693 T796 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42631_solr
[junit4:junit4]   2> 694 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:42631_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:42631_solr
[junit4:junit4]   2> 695 T796 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42631_solr
[junit4:junit4]   2> 704 T796 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 705 T796 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 705 T796 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 706 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 706 T796 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 707 T796 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 707 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 708 T796 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 708 T796 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 709 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 723 T796 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 733 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 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> 740 T796 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 742 T796 oasc.Overseer.start Overseer (id=88277275859746818-127.0.0.1:42631_solr-n_0000000000) starting
[junit4:junit4]   2> 743 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 744 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 751 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 752 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 754 T818 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 754 T796 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 764 T796 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 766 T817 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 771 T796 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1
[junit4:junit4]   2> 771 T796 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 772 T796 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 772 T796 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 774 T796 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/'
[junit4:junit4]   2> 775 T796 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/lib/README' to classloader
[junit4:junit4]   2> 776 T796 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 829 T796 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 898 T796 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1000 T796 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1006 T796 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1599 T796 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1610 T796 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1614 T796 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1627 T796 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1632 T796 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1637 T796 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1638 T796 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1639 T796 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/data/
[junit4:junit4]   2> 1640 T796 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcfe433
[junit4:junit4]   2> 1641 T796 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/data/index/
[junit4:junit4]   2> 1642 T796 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1642 T796 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/data/index forceNew:false
[junit4:junit4]   2> 1677 T796 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@755d2a59; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1678 T796 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1679 T796 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1681 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1682 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1682 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1683 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1684 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1684 T796 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1685 T796 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1685 T796 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1686 T796 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1687 T796 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1687 T796 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1688 T796 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1689 T796 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1689 T796 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1690 T796 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1690 T796 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1691 T796 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1692 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1692 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1693 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1694 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1694 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1695 T796 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1696 T796 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1696 T796 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1700 T796 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1705 T796 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1706 T796 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> 1710 T796 oass.SolrIndexSearcher.<init> Opening Searcher@5582a6b8 main
[junit4:junit4]   2> 1712 T796 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1712 T796 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1713 T796 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1731 T796 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1785 T819 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5582a6b8 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1790 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 type:create cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1802 T796 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1803 T796 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:42631/solr shardId:control_shard
[junit4:junit4]   2> 1803 T796 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1817 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 type:delete cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 1827 T796 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 1827 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1829 T796 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:42631/solr/collection1/
[junit4:junit4]   2> 1830 T796 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1833 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 type:create cxid:0x6f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2273 T817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2274 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0002 type:create cxid:0x77 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 2295 T816 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2345 T796 oasc.ZkController.register We are http://127.0.0.1:42631/solr/collection1/ and leader is http://127.0.0.1:42631/solr/collection1/
[junit4:junit4]   2> 2345 T796 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42631/solr
[junit4:junit4]   2> 2345 T796 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2347 T796 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2348 T796 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> 2348 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2348 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2457 T796 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2459 T796 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:64603
[junit4:junit4]   2> 2460 T796 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2460 T796 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-files/jetty2
[junit4:junit4]   2> 2460 T796 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-files/jetty2/'
[junit4:junit4]   2> 2488 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2489 T796 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2489 T796 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-files/jetty2
[junit4:junit4]   2> 2489 T796 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/solr.xml
[junit4:junit4]   2> 2490 T796 oasc.CoreContainer.<init> New CoreContainer 1400534125
[junit4:junit4]   2> 2490 T796 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/'
[junit4:junit4]   2> 2490 T796 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/'
[junit4:junit4]   2> 2512 T796 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2536 T796 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54312/solr
[junit4:junit4]   2> 2536 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54312/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@14d841b3
[junit4:junit4]   2> 2537 T829 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54312
[junit4:junit4]   2> 2537 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2538 T829 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:54312, initiating session
[junit4:junit4]   2> 2538 T798 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:26743
[junit4:junit4]   2> 2539 T798 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:26743
[junit4:junit4]   2> 2541 T800 oazs.NIOServerCnxn.finishSessionInit Established session 0x1399fb6f55c0003 with negotiated timeout 8000 for client /140.211.11.196:26743
[junit4:junit4]   2> 2541 T829 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:54312, sessionid = 0x1399fb6f55c0003, negotiated timeout = 8000
[junit4:junit4]   2> 2541 T830 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14d841b3 name:ZooKeeperConnection Watcher:127.0.0.1:54312/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2541 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2542 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2543 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2555 T796 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64603_solr
[junit4:junit4]   2> 2556 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:64603_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:64603_solr
[junit4:junit4]   2> 2556 T796 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:64603_solr
[junit4:junit4]   2> 2559 T816 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2559 T796 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2559 T796 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2560 T796 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2560 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2560 T796 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2561 T796 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2561 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2561 T796 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2562 T796 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2562 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2573 T796 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2578 T796 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1
[junit4:junit4]   2> 2579 T796 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2580 T796 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2580 T796 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2581 T796 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/'
[junit4:junit4]   2> 2582 T796 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2582 T796 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/lib/README' to classloader
[junit4:junit4]   2> 2617 T796 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2664 T796 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2765 T796 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2769 T796 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2800 T817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2812 T830 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2812 T816 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3280 T796 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3291 T796 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3295 T796 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3309 T796 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3314 T796 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3319 T796 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3320 T796 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3321 T796 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/data/
[junit4:junit4]   2> 3322 T796 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcfe433
[junit4:junit4]   2> 3323 T796 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/data/index/
[junit4:junit4]   2> 3324 T796 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3325 T796 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/data/index forceNew:false
[junit4:junit4]   2> 3332 T796 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@79d7b648; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3332 T796 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3333 T796 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3336 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3337 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3337 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3338 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3339 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3339 T796 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3340 T796 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3341 T796 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3341 T796 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3342 T796 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3343 T796 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3344 T796 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3344 T796 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3345 T796 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3345 T796 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3346 T796 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3347 T796 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3348 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3348 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3349 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3350 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3351 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3351 T796 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3352 T796 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3353 T796 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3357 T796 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3361 T796 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3363 T796 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> 3367 T796 oass.SolrIndexSearcher.<init> Opening Searcher@5255f271 main
[junit4:junit4]   2> 3368 T796 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3369 T796 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3370 T796 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3377 T796 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3437 T831 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5255f271 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3445 T796 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3445 T796 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:64603/solr shardId:shard3
[junit4:junit4]   2> 3446 T796 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
[junit4:junit4]   2> 3459 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0003 type:delete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 3461 T796 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 3461 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0003 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3470 T796 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:64603/solr/collection1/
[junit4:junit4]   2> 3471 T796 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
[junit4:junit4]   2> 3476 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0003 type:create cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3819 T817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3838 T816 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3838 T830 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3843 T796 oasc.ZkController.register We are http://127.0.0.1:64603/solr/collection1/ and leader is http://127.0.0.1:64603/solr/collection1/
[junit4:junit4]   2> 3843 T796 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:64603/solr
[junit4:junit4]   2> 3844 T796 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3845 T796 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3846 T796 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> 3847 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3847 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4011 T796 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4015 T796 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:39167
[junit4:junit4]   2> 4015 T796 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4016 T796 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-files/jetty3
[junit4:junit4]   2> 4016 T796 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-files/jetty3/'
[junit4:junit4]   2> 4058 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4059 T796 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4059 T796 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-files/jetty3
[junit4:junit4]   2> 4060 T796 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/solr.xml
[junit4:junit4]   2> 4060 T796 oasc.CoreContainer.<init> New CoreContainer 751035696
[junit4:junit4]   2> 4061 T796 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/'
[junit4:junit4]   2> 4061 T796 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/'
[junit4:junit4]   2> 4093 T796 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4109 T796 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54312/solr
[junit4:junit4]   2> 4110 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54312/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@2fb82f59
[junit4:junit4]   2> 4111 T841 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54312
[junit4:junit4]   2> 4111 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4112 T841 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:54312, initiating session
[junit4:junit4]   2> 4112 T798 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:30265
[junit4:junit4]   2> 4112 T798 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:30265
[junit4:junit4]   2> 4114 T800 oazs.NIOServerCnxn.finishSessionInit Established session 0x1399fb6f55c0004 with negotiated timeout 8000 for client /140.211.11.196:30265
[junit4:junit4]   2> 4114 T841 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:54312, sessionid = 0x1399fb6f55c0004, negotiated timeout = 8000
[junit4:junit4]   2> 4115 T842 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2fb82f59 name:ZooKeeperConnection Watcher:127.0.0.1:54312/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4115 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4116 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4127 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4128 T796 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39167_solr
[junit4:junit4]   2> 4129 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:39167_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:39167_solr
[junit4:junit4]   2> 4135 T796 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:39167_solr
[junit4:junit4]   2> 4137 T816 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4137 T830 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4137 T796 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4138 T796 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4138 T796 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4139 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4139 T796 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4140 T796 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4140 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4141 T796 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4141 T796 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4142 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4158 T796 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4165 T796 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1
[junit4:junit4]   2> 4165 T796 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4166 T796 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4166 T796 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4168 T796 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/'
[junit4:junit4]   2> 4169 T796 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/lib/README' to classloader
[junit4:junit4]   2> 4170 T796 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4222 T796 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4318 T796 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4344 T817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4373 T816 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4373 T830 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4419 T842 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4419 T796 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4430 T796 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5138 T796 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5150 T796 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5155 T796 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5169 T796 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5175 T796 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5180 T796 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5181 T796 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5183 T796 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/data/
[junit4:junit4]   2> 5183 T796 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcfe433
[junit4:junit4]   2> 5185 T796 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/data/index/
[junit4:junit4]   2> 5186 T796 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5186 T796 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/data/index forceNew:false
[junit4:junit4]   2> 5902 T796 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@4f196e15; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5903 T796 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5905 T796 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5908 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5909 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5910 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5910 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5911 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5912 T796 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5913 T796 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5914 T796 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5915 T796 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5916 T796 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5917 T796 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5917 T796 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5918 T796 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5919 T796 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5920 T796 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5921 T796 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5921 T796 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5922 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5923 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5924 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5925 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5926 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5927 T796 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5928 T796 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5928 T796 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5934 T796 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5940 T796 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5941 T796 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> 6163 T796 oass.SolrIndexSearcher.<init> Opening Searcher@33ab9fe main
[junit4:junit4]   2> 6165 T796 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6165 T796 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6166 T796 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6267 T796 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6439 T843 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@33ab9fe main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6446 T796 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6447 T796 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:39167/solr shardId:shard4
[junit4:junit4]   2> 6448 T796 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard4/election
[junit4:junit4]   2> 6472 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0004 type:delete cxid:0x41 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard4 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard4
[junit4:junit4]   2> 6481 T796 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 6482 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0004 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6497 T796 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:39167/solr/collection1/
[junit4:junit4]   2> 6497 T796 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard4
[junit4:junit4]   2> 6513 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0004 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6895 T817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6925 T816 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6925 T830 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6925 T842 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6938 T796 oasc.ZkController.register We are http://127.0.0.1:39167/solr/collection1/ and leader is http://127.0.0.1:39167/solr/collection1/
[junit4:junit4]   2> 6938 T796 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:39167/solr
[junit4:junit4]   2> 6938 T796 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 6941 T796 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6941 T796 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> 6941 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6942 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7099 T796 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 7103 T796 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:36553
[junit4:junit4]   2> 7103 T796 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7104 T796 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-files/jetty4
[junit4:junit4]   2> 7104 T796 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-files/jetty4/'
[junit4:junit4]   2> 7147 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 7148 T796 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7148 T796 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-files/jetty4
[junit4:junit4]   2> 7148 T796 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/solr.xml
[junit4:junit4]   2> 7149 T796 oasc.CoreContainer.<init> New CoreContainer 1971903214
[junit4:junit4]   2> 7150 T796 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/'
[junit4:junit4]   2> 7150 T796 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/'
[junit4:junit4]   2> 7185 T796 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 7202 T796 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:54312/solr
[junit4:junit4]   2> 7203 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54312/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@58d5ec8b
[junit4:junit4]   2> 7204 T853 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54312
[junit4:junit4]   2> 7204 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7205 T853 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:54312, initiating session
[junit4:junit4]   2> 7205 T798 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:21252
[junit4:junit4]   2> 7205 T798 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:21252
[junit4:junit4]   2> 7211 T800 oazs.NIOServerCnxn.finishSessionInit Established session 0x1399fb6f55c0005 with negotiated timeout 8000 for client /140.211.11.196:21252
[junit4:junit4]   2> 7211 T853 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:54312, sessionid = 0x1399fb6f55c0005, negotiated timeout = 8000
[junit4:junit4]   2> 7211 T854 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@58d5ec8b name:ZooKeeperConnection Watcher:127.0.0.1:54312/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 7211 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 7212 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7214 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7221 T796 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36553_solr
[junit4:junit4]   2> 7221 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:36553_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:36553_solr
[junit4:junit4]   2> 7222 T796 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36553_solr
[junit4:junit4]   2> 7224 T842 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 7225 T830 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 7224 T816 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 7225 T796 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 7226 T796 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 7226 T796 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 7227 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 7227 T796 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 7228 T796 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 7228 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 7229 T796 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 7229 T796 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 7230 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 7246 T796 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 7253 T796 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1
[junit4:junit4]   2> 7254 T796 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 7254 T796 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 7255 T796 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 7256 T796 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/'
[junit4:junit4]   2> 7258 T796 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 7259 T796 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/lib/README' to classloader
[junit4:junit4]   2> 7310 T796 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 7381 T796 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 7442 T817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7456 T842 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7456 T816 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7456 T830 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7483 T854 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7483 T796 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 7489 T796 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 8118 T796 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 8129 T796 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8134 T796 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8147 T796 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8152 T796 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8157 T796 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8159 T796 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8160 T796 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/data/
[junit4:junit4]   2> 8161 T796 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7fcfe433
[junit4:junit4]   2> 8162 T796 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/data/index/
[junit4:junit4]   2> 8163 T796 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 8164 T796 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/data/index forceNew:false
[junit4:junit4]   2> 8187 T796 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ad07503; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8188 T796 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 8189 T796 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 8192 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 8192 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 8193 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 8193 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 8194 T796 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 8195 T796 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 8195 T796 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 8196 T796 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 8197 T796 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 8198 T796 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 8198 T796 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 8199 T796 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 8200 T796 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 8200 T796 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 8201 T796 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 8202 T796 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 8202 T796 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8203 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8204 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8205 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8205 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8206 T796 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8207 T796 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8208 T796 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 8208 T796 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 8212 T796 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 8217 T796 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 8219 T796 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> 8223 T796 oass.SolrIndexSearcher.<init> Opening Searcher@2a964258 main
[junit4:junit4]   2> 8224 T796 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 8225 T796 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8226 T796 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 8233 T796 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 8286 T855 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2a964258 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8294 T796 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 8295 T796 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:36553/solr shardId:shard5
[junit4:junit4]   2> 8296 T796 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard5/election
[junit4:junit4]   2> 8308 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0005 type:delete cxid:0x41 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard5 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard5
[junit4:junit4]   2> 8309 T796 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 8310 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0005 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8312 T796 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:36553/solr/collection1/
[junit4:junit4]   2> 8312 T796 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard5
[junit4:junit4]   2> 8315 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x1399fb6f55c0005 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8462 T817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8513 T816 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8513 T854 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8513 T830 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8513 T842 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8521 T796 oasc.ZkController.register We are http://127.0.0.1:36553/solr/collection1/ and leader is http://127.0.0.1:36553/solr/collection1/
[junit4:junit4]   2> 8521 T796 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:36553/solr
[junit4:junit4]   2> 8522 T796 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 8525 T796 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8526 T796 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> 8526 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8527 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C75 name=collection1 org.apache.solr.core.SolrCore@5b454b9b url=http://127.0.0.1:42631/solr/collection1 node=127.0.0.1:42631_solr
[junit4:junit4]   2> 8555 T808 C75 P42631 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@755d2a59; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8557 T808 C75 P42631 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 8558 T808 C75 P42631 oass.SolrIndexSearcher.<init> Opening Searcher@45acdcab realtime
[junit4:junit4]   2> 8559 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412436422713933824)} 0 8
[junit4:junit4]   2> ASYNC  NEW_CORE C76 name=collection1 org.apache.solr.core.SolrCore@f2f5982 url=http://127.0.0.1:64603/solr/collection1 node=127.0.0.1:64603_solr
[junit4:junit4]   2> 8568 T822 C76 P64603 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@79d7b648; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8568 T822 C76 P64603 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 8569 T822 C76 P64603 oass.SolrIndexSearcher.<init> Opening Searcher@1066a9e9 realtime
[junit4:junit4]   2> ASYNC  NEW_CORE C77 name=collection1 org.apache.solr.core.SolrCore@ecb865b url=http://127.0.0.1:36553/solr/collection1 node=127.0.0.1:36553_solr
[junit4:junit4]   2> 8582 T846 C77 P36553 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ad07503; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> ASYNC  NEW_CORE C78 name=collection1 org.apache.solr.core.SolrCore@11db8401 url=http://127.0.0.1:39167/solr/collection1 node=127.0.0.1:39167_solr
[junit4:junit4]   2> 8582 T834 C78 P39167 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@4f196e15; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8583 T846 C77 P36553 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 8583 T834 C78 P39167 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 8584 T846 C77 P36553 oass.SolrIndexSearcher.<init> Opening Searcher@faa83be realtime
[junit4:junit4]   2> 8585 T834 C78 P39167 oass.SolrIndexSearcher.<init> Opening Searcher@64429aa0 realtime
[junit4:junit4]   2> 8585 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 8585 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 8586 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412436422727565312)} 0 21
[junit4:junit4]   2> 8986 T823 C76 P64603 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=395 
[junit4:junit4]   2> 8986 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=147 
[junit4:junit4]   2> 8987 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412436422751682560)} 0 399
[junit4:junit4]   2> 8994 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8994 T823 C76 P64603 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8995 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412436423173210112)} 0 6
[junit4:junit4]   2> 9002 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1412436423182647296)]} 0 4
[junit4:junit4]   2> 9008 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1412436423188938752)]} 0 3
[junit4:junit4]   2> 9012 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1412436423195230208)]} 0 2
[junit4:junit4]   2> 9017 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1412436423199424512)]} 0 2
[junit4:junit4]   2> 9022 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3 (1412436423204667392)]} 0 2
[junit4:junit4]   2> 9028 T817 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9036 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 9037 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 12
[junit4:junit4]   2> 9042 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4 (1412436423225638912)]} 0 2
[junit4:junit4]   2> 9047 T816 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9047 T842 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9047 T830 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9047 T854 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9050 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9050 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 5
[junit4:junit4]   2> 9055 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5 (1412436423240318976)]} 0 2
[junit4:junit4]   2>  C77_STATE=coll:collection1 core:collection1 props:{shard=shard5, leader=true, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:36553_solr, base_url=http://127.0.0.1:36553/solr}
[junit4:junit4]   2> 9064 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 9065 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 7
[junit4:junit4]   2> 9070 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6 (1412436423254999040)]} 0 2
[junit4:junit4]   2> 9076 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9077 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 5
[junit4:junit4]   2> 9082 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7 (1412436423267581952)]} 0 2
[junit4:junit4]   2> 9088 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9089 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 5
[junit4:junit4]   2> 9094 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8 (1412436423280164864)]} 0 2
[junit4:junit4]   2> 9101 T823 C76 P64603 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9102 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 6
[junit4:junit4]   2> 9107 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9 (1412436423293796352)]} 0 2
[junit4:junit4]   2> 9113 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9114 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 5
[junit4:junit4]   2> 9132 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10 (1412436423306379264)]} 0 15
[junit4:junit4]   2> 9135 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10 (1412436423324205056)]} 0 1
[junit4:junit4]   2> 9142 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11 (1412436423329447936)]} 0 3
[junit4:junit4]   2> 9150 T823 C76 P64603 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9150 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 5
[junit4:junit4]   2> 9155 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12 (1412436423344128000)]} 0 2
[junit4:junit4]   2> 9162 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9162 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 5
[junit4:junit4]   2> 9167 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13 (1412436423356710912)]} 0 2
[junit4:junit4]   2> 9171 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13 (1412436423361953792)]} 0 1
[junit4:junit4]   2> 9176 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14 (1412436423366148096)]} 0 2
[junit4:junit4]   2> 9183 T823 C76 P64603 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9183 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 5
[junit4:junit4]   2> 9187 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15 (1412436423378731008)]} 0 1
[junit4:junit4]   2> 9194 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9195 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 5
[junit4:junit4]   2> 9200 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16 (1412436423391313920)]} 0 1
[junit4:junit4]   2> 9203 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16 (1412436423395508224)]} 0 1
[junit4:junit4]   2> 9210 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17 (1412436423401799680)]} 0 3
[junit4:junit4]   2> 9220 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 9221 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 8
[junit4:junit4]   2> 9226 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100 (1412436423419625472)]} 0 2
[junit4:junit4]   2> 9233 T823 C76 P64603 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9234 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 5
[junit4:junit4]   2> 9238 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101 (1412436423432208384)]} 0 1
[junit4:junit4]   2> 9245 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9246 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 5
[junit4:junit4]   2> 9264 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102 (1412436423444791296)]} 0 15
[junit4:junit4]   2> 9282 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=12 
[junit4:junit4]   2> 9283 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 16
[junit4:junit4]   2> 9289 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103 (1412436423483588608)]} 0 3
[junit4:junit4]   2> 9297 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9297 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 5
[junit4:junit4]   2> 9302 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104 (1412436423499317248)]} 0 2
[junit4:junit4]   2> 9307 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104 (1412436423503511552)]} 0 2
[junit4:junit4]   2> 9311 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105 (1412436423508754432)]} 0 1
[junit4:junit4]   2> 9319 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9320 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 6
[junit4:junit4]   2> 9324 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106 (1412436423522385920)]} 0 1
[junit4:junit4]   2> 9331 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9332 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 5
[junit4:junit4]   2> 9336 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107 (1412436423534968832)]} 0 1
[junit4:junit4]   2> 9343 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9344 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 5
[junit4:junit4]   2> 9348 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108 (1412436423547551744)]} 0 1
[junit4:junit4]   2> 9352 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108 (1412436423551746048)]} 0 1
[junit4:junit4]   2> 9356 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109 (1412436423555940352)]} 0 1
[junit4:junit4]   2> 9383 T823 C76 P64603 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=21 
[junit4:junit4]   2> 9384 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 25
[junit4:junit4]   2> 9388 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110 (1412436423590543360)]} 0 1
[junit4:junit4]   2> 9398 T823 C76 P64603 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 9399 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 8
[junit4:junit4]   2> 9403 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1412436423605223424)]} 0 1
[junit4:junit4]   2> 9410 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9411 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 5
[junit4:junit4]   2> 9428 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112 (1412436423617806336)]} 0 14
[junit4:junit4]   2> 9435 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9436 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 5
[junit4:junit4]   2> 9442 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113 (1412436423644020736)]} 0 3
[junit4:junit4]   2> 9448 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9449 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 5
[junit4:junit4]   2> 9454 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114 (1412436423658700800)]} 0 2
[junit4:junit4]   2> 9463 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9463 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 5
[junit4:junit4]   2> 9467 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115 (1412436423672332288)]} 0 1
[junit4:junit4]   2> 9471 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115 (1412436423676526592)]} 0 1
[junit4:junit4]   2> 9475 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116 (1412436423680720896)]} 0 1
[junit4:junit4]   2> 9479 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116 (1412436423684915200)]} 0 1
[junit4:junit4]   2> 9483 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117 (1412436423689109504)]} 0 1
[junit4:junit4]   2> 9490 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9491 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 5
[junit4:junit4]   2> 9495 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118 (1412436423701692416)]} 0 1
[junit4:junit4]   2> 9502 T823 C76 P64603 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9503 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 5
[junit4:junit4]   2> 9508 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119 (1412436423715323904)]} 0 2
[junit4:junit4]   2> 9516 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9516 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 5
[junit4:junit4]   2> 9522 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120 (1412436423730003968)]} 0 2
[junit4:junit4]   2> 9529 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9530 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 5
[junit4:junit4]   2> 9535 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121 (1412436423742586880)]} 0 2
[junit4:junit4]   2> 9541 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9542 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 5
[junit4:junit4]   2> 9555 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122 (1412436423756218368)]} 0 10
[junit4:junit4]   2> 9570 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 9571 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 13
[junit4:junit4]   2> 9576 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1412436423785578496)]} 0 2
[junit4:junit4]   2> 9580 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1412436423790821376)]} 0 1
[junit4:junit4]   2> 9584 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124 (1412436423795015680)]} 0 1
[junit4:junit4]   2> 9593 T823 C76 P64603 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 9594 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 7
[junit4:junit4]   2> 9599 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125 (1412436423810744320)]} 0 2
[junit4:junit4]   2> 9608 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 9608 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 6
[junit4:junit4]   2> 9613 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126 (1412436423825424384)]} 0 1
[junit4:junit4]   2> 9620 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9621 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 5
[junit4:junit4]   2> 9625 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127 (1412436423838007296)]} 0 1
[junit4:junit4]   2> 9641 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 9641 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 13
[junit4:junit4]   2> 9646 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128 (1412436423861075968)]} 0 1
[junit4:junit4]   2> 9652 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128 (1412436423864221696)]} 0 3
[junit4:junit4]   2> 9656 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129 (1412436423870513152)]} 0 1
[junit4:junit4]   2> 9663 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9664 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 5
[junit4:junit4]   2> 9668 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130 (1412436423883096064)]} 0 1
[junit4:junit4]   2> 9673 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130 (1412436423888338944)]} 0 2
[junit4:junit4]   2> 9677 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131 (1412436423892533248)]} 0 1
[junit4:junit4]   2> 9681 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131 (1412436423896727552)]} 0 1
[junit4:junit4]   2> 9697 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132 (1412436423900921856)]} 0 13
[junit4:junit4]   2> 9701 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132 (1412436423917699072)]} 0 1
[junit4:junit4]   2> 9706 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133 (1412436423921893376)]} 0 2
[junit4:junit4]   2> 9710 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133 (1412436423927136256)]} 0 1
[junit4:junit4]   2> 9714 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134 (1412436423931330560)]} 0 1
[junit4:junit4]   2> 9721 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9722 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 5
[junit4:junit4]   2> 9726 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135 (1412436423943913472)]} 0 1
[junit4:junit4]   2> 9730 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135 (1412436423948107776)]} 0 1
[junit4:junit4]   2> 9734 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136 (1412436423952302080)]} 0 1
[junit4:junit4]   2> 9741 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9742 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 5
[junit4:junit4]   2> 9746 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137 (1412436423964884992)]} 0 1
[junit4:junit4]   2> 9750 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137 (1412436423969079296)]} 0 1
[junit4:junit4]   2> 9754 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138 (1412436423973273600)]} 0 1
[junit4:junit4]   2> 9758 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138 (1412436423977467904)]} 0 1
[junit4:junit4]   2> 9762 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139 (1412436423981662208)]} 0 1
[junit4:junit4]   2> 9769 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9769 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139]} 0 4
[junit4:junit4]   2> 9774 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140 (1412436423994245120)]} 0 2
[junit4:junit4]   2> 9780 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9781 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 5
[junit4:junit4]   2> 9785 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141 (1412436424005779456)]} 0 1
[junit4:junit4]   2> 9793 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9797 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141]} 0 9
[junit4:junit4]   2> 9815 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142 (1412436424025702400)]} 0 12
[junit4:junit4]   2> 9822 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9823 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142]} 0 5
[junit4:junit4]   2> 9828 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143 (1412436424049819648)]} 0 2
[junit4:junit4]   2> 9835 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9836 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143]} 0 5
[junit4:junit4]   2> 9840 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144 (1412436424063451136)]} 0 1
[junit4:junit4]   2> 9847 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9847 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 4
[junit4:junit4]   2> 9851 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145 (1412436424074985472)]} 0 1
[junit4:junit4]   2> 9858 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9859 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 5
[junit4:junit4]   2> 9863 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146 (1412436424087568384)]} 0 1
[junit4:junit4]   2> 9880 T834 C78 P39167 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:36553/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=11 
[junit4:junit4]   2> 9881 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 15
[junit4:junit4]   2> 9885 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147 (1412436424110637056)]} 0 1
[junit4:junit4]   2> 9892 T846 C77 P36553 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:64603/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9892 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 4
[junit4:junit4]   2> 9896 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148 (1412436424122171392)]} 0 1
[junit4:junit4]   2> 9904 T823 C76 P64603 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:39167/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 9905 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148]} 0 6
[junit4:junit4]   2> 9909 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149 (1412436424135802880)]} 0 1
[junit4:junit4]   2> 9913 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149 (1412436424139997184)]} 0 1
[junit4:junit4]   2> 9916 T808 C75 P42631 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10461 T808 C75 P42631 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@755d2a59; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/control1/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@755d2a59; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_5_nrm.cfe, _3_MockVariableIntBlock_0.frq, _4_MockSep_0.skp, _6_Direct_0.tip, _6_Direct_0.tim, _3.fdt, _4_MockVariableIntBlock_0.tii, _5_Lucene40WithOrds_0.tib, _2_MockSep_0.doc, _0_Lucene40WithOrds_0.frq, _5_Lucene40WithOrds_0.tii, _1_Lucene40WithOrds_0.tib, _6_MockSep_0.doc, _5_MockSep_0.skp, _1_Lucene40WithOrds_0.tii, _6_MockSep_0.frq, _6_MockVariableIntBlock_0.tii, _1_MockSep_0.pyl, _1_Lucene40WithOrds_0.frq, _5_Direct_0.prx, _6_MockVariableIntBlock_0.tib, _1_MockSep_0.tib, _1_MockVariableIntBlock_0.tii, _5_MockSep_0.doc, _1_MockVariableIntBlock_0.tib, _1_MockSep_0.tii, _1.fdx, _4_MockVariableIntBlock_0.tib, _1.fdt, _3_MockSep_0.pos, _5_nrm.cfs, _0_MockVariableIntBlock_0.frq, _6.fdt, _5_Lucene40WithOrds_0.prx, _2.si, _1_MockVariableIntBlock_0.skp, _0_MockVariableIntBlock_0.doc, _6_MockSep_0.pyl, _2_MockSep_0.skp, _6.fdx, _1_Lucene40WithOrds_0.prx, _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, _0_Lucene40WithOrds_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, _3_Lucene40WithOrds_0.tii, _4_nrm.cfe, _6_Lucene40WithOrds_0.prx, _3_Lucene40WithOrds_0.tib, _4.fdx, _4_Direct_0.frq, _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_Lucene40WithOrds_0.prx, _4_nrm.cfs, _3_MockVariableIntBlock_0.tib, _3_Direct_0.tim, _5_MockSep_0.pyl, _5_MockVariableIntBlock_0.doc, _6_MockVariableIntBlock_0.pyl, _2_MockVariableIntBlock_0.frq, _6_nrm.cfs, _2_Lucene40WithOrds_0.prx, _0_MockSep_0.doc, _1_nrm.cfs, _5.fnm, _6_nrm.cfe, _2_MockSep_0.frq, _3_Lucene40WithOrds_0.frq, _4.fdt, _4.si, _1_nrm.cfe, _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, _0_Lucene40WithOrds_0.tii, _4_MockSep_0.doc, _5_MockVariableIntBlock_0.tii, _0_Lucene40WithOrds_0.tib, _1_MockSep_0.skp, _4_MockVariableIntBlock_0.doc, _2.fdt, _2.fdx, _4_MockSep_0.pyl, _3.si, _2_MockVariableIntBlock_0.skp, _4_MockSep_0.tii, _5_Direct_0.tim, _4_Lucene40WithOrds_0.tib, _2_MockVariableIntBlock_0.pyl, _4_Lucene40WithOrds_0.tii, _3.fnm, _3_MockSep_0.pyl, _2_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pyl, _1_MockVariableIntBlock_0.doc, _6_MockSep_0.pos, _5_Direct_0.tip, _2_MockVariableIntBlock_0.tii, _1_MockSep_0.pos, _2_Lucene40WithOrds_0.frq, _5_MockVariableIntBlock_0.tib, _2_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.pos, _6_MockSep_0.skp, _6_Direct_0.prx, _3_MockVariableIntBlock_0.pyl, _6.si, _6.fnm, _3_Lucene40WithOrds_0.prx, _3_Direct_0.frq, _5_MockSep_0.pos, _5.fdt, _4.fnm, _0_MockVariableIntBlock_0.skp, _1.fnm, _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, _6_Lucene40WithOrds_0.frq, _4_MockSep_0.pos, _4_Direct_0.tim, _5_MockSep_0.frq, _2_MockSep_0.tii, _5_Direct_0.frq, _2_MockSep_0.tib, _3_MockSep_0.tii, _6_Lucene40WithOrds_0.tii, _5_Lucene40WithOrds_0.frq, _6_Lucene40WithOrds_0.tib, _4_Direct_0.prx, _4_Direct_0.tip, _2_MockSep_0.pos, _1_MockSep_0.frq, _4_Lucene40WithOrds_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, _2_Lucene40WithOrds_0.tii, _1_MockVariableIntBlock_0.frq, _0_nrm.cfe, segments_2, _4_MockSep_0.frq, _5.fdx, _2_Lucene40WithOrds_0.tib, _0.fdx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _6_Direct_0.frq, _3_MockVariableIntBlock_0.skp, _0.fdt]
[junit4:junit4]   2> 10462 T808 C75 P42631 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 10477 T808 C75 P42631 oass.SolrIndexSearcher.<init> Opening Searcher@6635689e main
[junit4:junit4]   2> 10478 T808 C75 P42631 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10480 T819 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6635689e 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> 10481 T808 C75 P42631 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 565
[junit4:junit4]   2> 10484 T822 C76 P64603 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10636 T822 C76 P64603 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@79d7b648; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty2/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@79d7b648; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0_MockSep_0.pos, _0_MockVariableIntBlock_0.skp, _1.fnm, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0_MockSep_0.skp, _1_Lucene40WithOrds_0.tib, _1_Direct_0.tim, _1_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.tib, _1_Direct_0.tip, _1_MockSep_0.pyl, _1_MockSep_0.doc, _1_Lucene40WithOrds_0.frq, _1_MockSep_0.skp, _0.fnm, _1_MockSep_0.tib, _1_MockVariableIntBlock_0.tii, _0_Direct_0.frq, _1_MockVariableIntBlock_0.tib, _1_MockSep_0.tii, _1.fdx, _1.fdt, _0_MockVariableIntBlock_0.frq, _1_MockSep_0.frq, _1_MockVariableIntBlock_0.skp, _0_MockVariableIntBlock_0.doc, _1_Lucene40WithOrds_0.prx, _1_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _0_MockSep_0.doc, _0.si, _0_MockVariableIntBlock_0.tii, _1_nrm.cfs, _1_MockSep_0.pos, _1_MockVariableIntBlock_0.pos, _0_nrm.cfs, _1_Direct_0.prx, _1_nrm.cfe, _0_MockSep_0.tib, _1_MockVariableIntBlock_0.frq, _1.si, _0_nrm.cfe, _0_MockSep_0.frq, segments_2, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0_Lucene40WithOrds_0.prx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq]
[junit4:junit4]   2> 10637 T822 C76 P64603 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 10652 T822 C76 P64603 oass.SolrIndexSearcher.<init> Opening Searcher@7d51275f main
[junit4:junit4]   2> 10654 T822 C76 P64603 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10655 T831 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7d51275f main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C8)}
[junit4:junit4]   2> 10655 T822 C76 P64603 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:39167/solr/collection1/, StdNode: http://127.0.0.1:36553/solr/collection1/]
[junit4:junit4]   2> 10658 T834 C78 P39167 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10658 T846 C77 P36553 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10827 T846 C77 P36553 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ad07503; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty4/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ad07503; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_0_MockSep_0.pos, _0_MockVariableIntBlock_0.skp, _1.fnm, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0_MockSep_0.skp, _1_Lucene40WithOrds_0.tib, _1_Direct_0.tim, _1_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.tib, _1_Direct_0.tip, _1_MockSep_0.pyl, _1_MockSep_0.doc, _1_Lucene40WithOrds_0.frq, _1_MockSep_0.skp, _0.fnm, _1_MockSep_0.tib, _1_MockVariableIntBlock_0.tii, _0_Direct_0.frq, _1_MockVariableIntBlock_0.tib, _1_MockSep_0.tii, _1.fdx, _1.fdt, _0_MockVariableIntBlock_0.frq, _1_MockSep_0.frq, _1_MockVariableIntBlock_0.skp, _0_MockVariableIntBlock_0.doc, _1_Lucene40WithOrds_0.prx, _1_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _0_MockSep_0.doc, _0.si, _0_MockVariableIntBlock_0.tii, _1_nrm.cfs, _1_MockSep_0.pos, _1_MockVariableIntBlock_0.pos, _0_nrm.cfs, _1_Direct_0.prx, _1_nrm.cfe, _0_MockSep_0.tib, _1_MockVariableIntBlock_0.frq, _1.si, _0_nrm.cfe, _0_MockSep_0.frq, segments_2, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0_Lucene40WithOrds_0.prx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq]
[junit4:junit4]   2> 10828 T846 C77 P36553 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 10838 T846 C77 P36553 oass.SolrIndexSearcher.<init> Opening Searcher@2573f5ed main
[junit4:junit4]   2> 10839 T846 C77 P36553 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10840 T855 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2573f5ed main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 10840 T846 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 182
[junit4:junit4]   2> 10843 T834 C78 P39167 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@4f196e15; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build/solr-core/test-files/jetty3/collection1/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@4f196e15; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_2_Direct_0.tip, _2_Direct_0.tim, _0_MockSep_0.pos, _0_MockVariableIntBlock_0.skp, _2_MockSep_0.doc, _1.fnm, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0_MockSep_0.skp, _1_Lucene40WithOrds_0.tib, _1_Direct_0.tim, _1_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.tib, _1_Direct_0.tip, _2_MockVariableIntBlock_0.pos, _1_MockSep_0.pyl, _1_MockSep_0.doc, _1_Lucene40WithOrds_0.frq, _1_MockSep_0.skp, _0.fnm, _1_MockSep_0.tib, _1_MockVariableIntBlock_0.tii, _2.fdt, _2_MockSep_0.tii, _2_MockSep_0.tib, _0_Direct_0.frq, _2.fdx, _1_MockVariableIntBlock_0.tib, _1_MockSep_0.tii, _1.fdx, _1.fdt, _0_MockVariableIntBlock_0.frq, _2_MockVariableIntBlock_0.skp, _2_MockSep_0.pos, _2.si, _1_MockSep_0.frq, _1_MockVariableIntBlock_0.skp, _2_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.doc, _2_MockSep_0.skp, _1_Lucene40WithOrds_0.prx, _2_MockVariableIntBlock_0.frq, _1_MockVariableIntBlock_0.pyl, _2_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pyl, _2_nrm.cfe, _2_Lucene40WithOrds_0.prx, _0_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _2_nrm.cfs, _0_MockSep_0.doc, _0.si, _0_MockVariableIntBlock_0.tii, _2_MockVariableIntBlock_0.tii, _1_nrm.cfs, _1_MockSep_0.pos, _2_Lucene40WithOrds_0.frq, _2_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.pos, _2_MockSep_0.frq, _0_nrm.cfs, _1_Direct_0.prx, _2.fnm, _1_nrm.cfe, _0_MockSep_0.tib, _2_Direct_0.prx, _2_Lucene40WithOrds_0.tii, _1_MockVariableIntBlock_0.frq, _2_MockSep_0.pyl, _1.si, _0_nrm.cfe, _0_MockSep_0.frq, segments_2, _2_Direct_0.frq, _2_Lucene40WithOrds_0.tib, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0_Lucene40WithOrds_0.prx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq]
[junit4:junit4]   2> 10844 T834 C78 P39167 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 10854 T834 C78 P39167 oass.SolrIndexSearcher.<init> Opening Searcher@51c8cf41 main
[junit4:junit4]   2> 10855 T834 C78 P39167 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10856 T843 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@51c8cf41 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10)}
[junit4:junit4]   2> 10856 T834 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 198
[junit4:junit4]   2> 10857 T822 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 373
[junit4:junit4]   2> 10860 T835 C78 P39167 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10862 T835 C78 P39167 oass.SolrIndexSearcher.<init> Opening Searcher@3d20b84e main
[junit4:junit4]   2> 10862 T835 C78 P39167 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10863 T843 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3d20b84e main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10)}
[junit4:junit4]   2> 10864 T835 C78 P39167 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:64603/solr/collection1/, StdNode: http://127.0.0.1:36553/solr/collection1/]
[junit4:junit4]   2> 10866 T846 C77 P36553 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10867 T823 C76 P64603 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10867 T846 C77 P36553 oass.SolrIndexSearcher.<init> Opening Searcher@2a3cf5f2 main
[junit4:junit4]   2> 10868 T846 C77 P36553 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10868 T823 C76 P64603 oass.SolrIndexSearcher.<init> Opening Searcher@50f6acee main
[junit4:junit4]   2> 10869 T823 C76 P64603 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10869 T855 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2a3cf5f2 main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 10869 T846 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 3
[junit4:junit4]   2> 10870 T831 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50f6acee main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C8)}
[junit4:junit4]   2> 10871 T823 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 4
[junit4:junit4]   2> 10871 T835 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 11
[junit4:junit4]   2> 10874 T847 C77 P36553 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10875 T847 C77 P36553 oass.SolrIndexSearcher.<init> Opening Searcher@2dfbd1ea main
[junit4:junit4]   2> 10876 T847 C77 P36553 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10877 T855 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2dfbd1ea main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 10877 T847 C77 P36553 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:64603/solr/collection1/, StdNode: http://127.0.0.1:39167/solr/collection1/]
[junit4:junit4]   2> 10880 T823 C76 P64603 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10880 T834 C78 P39167 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10881 T823 C76 P64603 oass.SolrIndexSearcher.<init> Opening Searcher@4b3882ab main
[junit4:junit4]   2> 10881 T834 C78 P39167 oass.SolrIndexSearcher.<init> Opening Searcher@6b0acf21 main
[junit4:junit4]   2> 10881 T823 C76 P64603 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10881 T834 C78 P39167 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10882 T831 oasc.JmxMonitoredMap.put WARNING Failed to register info bean: searcher javax.management.InstanceNotFoundException: solr/collection1:type=searcher,id=org.apache.solr.search.SolrIndexSearcher
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1095)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:427)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4:junit4]   2> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:536)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.put(JmxMonitoredMap.java:138)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.put(JmxMonitoredMap.java:51)
[junit4:junit4]   2> 	at org.apache.solr.search.SolrIndexSearcher.register(SolrIndexSearcher.java:242)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore.registerSearcher(SolrCore.java:1634)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore.access$000(SolrCore.java:121)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore$7.call(SolrCore.java:1511)
[junit4:junit4]   2> 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
[junit4:junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
[junit4:junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[junit4:junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[junit4:junit4]   2> 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 
[junit4:junit4]   2> 10883 T843 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6b0acf21 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10)}
[junit4:junit4]   2> 10883 T834 C78 P39167 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 3
[junit4:junit4]   2> 10884 T831 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4b3882ab main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C8)}
[junit4:junit4]   2> 10885 T823 C76 P64603 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 5
[junit4:junit4]   2> 10885 T847 C77 P36553 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 11
[junit4:junit4]   2> 10890 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_ti1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 10900 T836 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342242&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 10900 T848 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342242&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 10901 T824 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342242&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 10906 T836 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342242&q=*:*&ids=122,140&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10906 T848 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342242&q=*:*&ids=116,149,5&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10906 T824 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342242&q=*:*&ids=135,138,124,100,118&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10907 T822 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=12 
[junit4:junit4]   2> 10912 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_ti1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 10915 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 10927 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:54312/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@36f3bc82
[junit4:junit4]   2> 10928 T865 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:54312
[junit4:junit4]   2> 10928 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10929 T865 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:54312, initiating session
[junit4:junit4]   2> 10929 T798 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:43319
[junit4:junit4]   2> 10930 T798 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:43319
[junit4:junit4]   2> 10935 T800 oazs.NIOServerCnxn.finishSessionInit Established session 0x1399fb6f55c0006 with negotiated timeout 10000 for client /140.211.11.196:43319
[junit4:junit4]   2> 10935 T865 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:54312, sessionid = 0x1399fb6f55c0006, negotiated timeout = 10000
[junit4:junit4]   2> 10935 T866 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@36f3bc82 name:ZooKeeperConnection Watcher:127.0.0.1:54312/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10935 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10936 T796 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 10948 T825 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347004342291&shard.url=127.0.0.1:64603/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 10948 T837 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347004342291&shard.url=127.0.0.1:39167/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 10948 T850 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347004342291&shard.url=127.0.0.1:36553/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 10954 T825 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342291&q=*:*&ids=13&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10954 T837 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342291&q=*:*&ids=3,2,146,15,12&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10954 T850 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342291&q=*:*&ids=126,127,139,106&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10956 T849 C77 P36553 REQ [collection1] webapp=/solr path=/select params={collection=collection1&sort=n_ti1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=12 
[junit4:junit4]   2> 10961 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_f1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 10968 T825 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342312&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 10969 T837 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342312&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 10969 T850 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342312&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 10974 T825 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342312&q=*:*&ids=135,100&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10974 T837 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342312&q=*:*&ids=125,3,133,122,105&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10974 T850 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342312&q=*:*&ids=116,5,103&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10976 T849 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&shards=shard3,shard4,shard5&q=*:*&collection=collection1&wt=javabin&version=2} status=0 QTime=11 
[junit4:junit4]   2> 10980 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_f1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 10987 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342331&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 10987 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342331&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 10988 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342331&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 10991 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342331&q=*:*&ids=8,14&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10992 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342331&q=*:*&ids=149,104,142&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10992 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342331&q=*:*&ids=143,2,114,146,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10994 T838 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=10 
[junit4:junit4]   2> 11000 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tf1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11006 T824 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342351&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 11006 T836 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342351&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 11007 T848 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342351&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11047 T824 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342351&q=*:*&ids=10,108&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11047 T848 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342351&q=*:*&ids=126,149,106,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11048 T836 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342351&q=*:*&ids=134,6,107,102&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11051 T822 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=47 
[junit4:junit4]   2> 11055 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tf1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11062 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347004342406&shard.url=127.0.0.1:39167/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 11062 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347004342406&shard.url=127.0.0.1:36553/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11063 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347004342406&shard.url=127.0.0.1:64603/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 11067 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342406&q=*:*&ids=7,15,119&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11067 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342406&q=*:*&ids=116,121,131&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11068 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342406&q=*:*&ids=138,109,16,100&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11070 T838 C78 P39167 REQ [collection1] webapp=/solr path=/select params={collection=collection1&sort=n_tf1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=11 
[junit4:junit4]   2> 11075 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_d1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11081 T825 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342425&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 11082 T850 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342425&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 11082 T837 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342425&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 11086 T825 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342425&q=*:*&ids=148,110&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11088 T837 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342425&q=*:*&ids=134,143,144,113,4,119&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11088 T850 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342425&q=*:*&ids=149,137&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11091 T849 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=13 
[junit4:junit4]   2> 11095 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_d1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11101 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342446&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11102 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342446&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 11102 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342446&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 11106 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342446&q=*:*&ids=120,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11107 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342446&q=*:*&ids=145,127,147&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11107 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342446&q=*:*&ids=1,138,123,11,118&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11109 T835 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=10 
[junit4:junit4]   2> 11113 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_td1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11121 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342464&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 11121 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342464&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 11121 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342464&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 11124 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342464&q=*:*&ids=145,136&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11125 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342464&q=*:*&ids=117,122,15,141&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11126 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342464&q=*:*&ids=132,138,148,11&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11128 T838 C78 P39167 REQ [collection1] webapp=/solr path=/select params={collection=collection1&sort=n_td1+desc&wt=javabin&q=*:*&version=2} status=0 QTime=11 
[junit4:junit4]   2> 11133 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_td1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 11138 T848 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342482&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11138 T836 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342482&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 11138 T824 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342482&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 11141 T824 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342482&q=*:*&ids=124,8&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11141 T848 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342482&q=*:*&ids=127,149,103,131&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11142 T836 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342482&q=*:*&ids=144,4,9,130&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11143 T827 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=8 
[junit4:junit4]   2> 11147 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_l1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11151 T825 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342496&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 11151 T850 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342496&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11152 T837 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342496&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 11156 T825 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342496&q=*:*&ids=135,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11156 T837 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342496&q=*:*&ids=113,130,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11157 T850 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342496&q=*:*&ids=126,139,5,137,142&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11158 T847 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 11161 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_l1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 11166 T836 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342511&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 11166 T848 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342511&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11167 T824 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342511&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 11171 T836 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342511&q=*:*&ids=2,144,9,129&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11172 T848 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342511&q=*:*&ids=136,106,104,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11172 T824 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342511&q=*:*&ids=110,124&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11173 T822 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 11177 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11182 T848 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342527&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11182 T836 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342527&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 11183 T824 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342527&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 11186 T848 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342527&q=*:*&ids=116,112,136,106&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11186 T836 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342527&q=*:*&ids=143,6,4,105,102,141&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11187 T822 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=7 
[junit4:junit4]   2> 11191 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tl1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 11198 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342541&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11198 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342541&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 11198 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342541&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 11202 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342541&q=*:*&ids=147,131&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11202 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342541&q=*:*&ids=10,148&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11202 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342541&q=*:*&ids=125,133,111,120,15,119&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11203 T835 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 11206 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_dt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 11212 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342556&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 11212 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342556&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 11212 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342556&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11216 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342556&q=*:*&ids=10,148,16,14&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11216 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342556&q=*:*&ids=17,104&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11217 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342556&q=*:*&ids=7,6,129,141&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11218 T838 C78 P39167 REQ [collection1] webapp=/solr path=/select params={collection=collection1&sort=n_dt1+desc&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 11222 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_dt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 11228 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342572&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 11229 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342572&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 11228 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342572&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11233 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342572&q=*:*&ids=112,5&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11233 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342572&q=*:*&ids=1,118&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11233 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342572&q=*:*&ids=125,114,122,4,15,120&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11234 T835 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 11239 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tdt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11245 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342589&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 11246 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342589&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 11246 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342589&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 11249 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342589&q=*:*&ids=112,136,106&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11249 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342589&q=*:*&ids=125,117,15&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11250 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342589&q=*:*&ids=1,138,110,100&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11251 T835 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 11254 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tdt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 11260 T837 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342604&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 11260 T825 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342604&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 11260 T850 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342604&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11264 T850 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342604&q=*:*&ids=137,131&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11264 T825 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342604&q=*:*&ids=109,124,16,11&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11265 T837 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342604&q=*:*&ids=134,143,7,111&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11266 T847 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 11271 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=a_si+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11278 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342621&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 11278 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342621&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 11278 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342621&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 11281 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342621&q=*:*&ids=5&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11282 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342621&q=*:*&ids=3,2,7,9,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11282 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342621&q=*:*&ids=10,1,8,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11283 T838 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} status=0 QTime=9 
[junit4:junit4]   2> 11287 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=a_si+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 11293 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={sort=a_si+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342637&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 11293 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=a_si+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342637&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11293 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=a_si+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342637&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 11303 T826 C76 P64603 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:64603/solr/collection1/&NOW=1347004342637&q=*:*&ids=1,13,11&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 11327 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342637&q=*:*&ids=3,2,7,6,4,9,12&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=24 
[junit4:junit4]   2> 11329 T838 C78 P39167 REQ [collection1] webapp=/solr path=/select params={collection=collection1&sort=a_si+asc&wt=javabin&q=*:*&version=2} status=0 QTime=39 
[junit4:junit4]   2> 11334 T808 C75 P42631 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&fl=*,score&q=*:*&distrib=false&wt=javabin&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 11340 T851 C77 P36553 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:36553/solr/collection1/&NOW=1347004342684&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 11341 T839 C78 P39167 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:39167/solr/collection1/&NOW=1347004342684&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[

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

true -Dtests.locale=fi_FI -Dtests.timezone=America/Matamoros -Dtests.file.encoding=US-ASCII
[junit4:junit4] FAILURE  380s J0 | BasicDistributedZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: some core start times did not change on reload
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([43C493D765ED4C39:C2221DCF12B22C05]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.junit.Assert.assertTrue(Assert.java:43)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZkTest.testCollectionsAPI(BasicDistributedZkTest.java:409)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZkTest.doTest(BasicDistributedZkTest.java:313)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:691)
[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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 379662 T796 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {n_f1=PostingsFormat(name=Direct), foo_b=MockVariableIntBlock(baseBlockSize=87), cat=PostingsFormat(name=Lucene40WithOrds), foo_d=PostingsFormat(name=MockSep), foo_f=MockVariableIntBlock(baseBlockSize=87), n_tl1=PostingsFormat(name=Direct), n_d1=PostingsFormat(name=Lucene40WithOrds), rnd_b=PostingsFormat(name=MockSep), intDefault=PostingsFormat(name=Lucene40WithOrds), n_td1=PostingsFormat(name=Direct), timestamp=PostingsFormat(name=Lucene40WithOrds), id=PostingsFormat(name=MockSep), range_facet_sl=MockVariableIntBlock(baseBlockSize=87), range_facet_si=PostingsFormat(name=Direct), oddField_s=MockVariableIntBlock(baseBlockSize=87), sequence_i=MockVariableIntBlock(baseBlockSize=87), name=PostingsFormat(name=MockSep), foo_i=PostingsFormat(name=Direct), regex_dup_B_s=PostingsFormat(name=Lucene40WithOrds), multiDefault=PostingsFormat(name=Direct), n_tf1=PostingsFormat(name=Lucene40WithOrds), n_dt1=PostingsFormat(name=Direct), genre_s=MockVariableIntBlock(baseBlockSize=87), author_t=PostingsFormat(name=Direct), n_ti1=PostingsFormat(name=MockSep), range_facet_l=PostingsFormat(name=Lucene40WithOrds), text=MockVariableIntBlock(baseBlockSize=87), _version_=PostingsFormat(name=Lucene40WithOrds), val_i=PostingsFormat(name=MockSep), SubjectTerms_mfacet=PostingsFormat(name=Lucene40WithOrds), series_t=PostingsFormat(name=Direct), a_t=PostingsFormat(name=Lucene40WithOrds), n_tdt1=PostingsFormat(name=Lucene40WithOrds), regex_dup_A_s=MockVariableIntBlock(baseBlockSize=87), price=MockVariableIntBlock(baseBlockSize=87), other_tl1=PostingsFormat(name=Lucene40WithOrds), n_l1=PostingsFormat(name=Lucene40WithOrds), a_si=PostingsFormat(name=Direct), inStock=MockVariableIntBlock(baseBlockSize=87)}, sim=DefaultSimilarity, locale=fi_FI, timezone=America/Matamoros
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Oracle Corporation 1.7.0_06 (64-bit)/cpus=16,threads=1,free=73554360,total=401604608
[junit4:junit4]   2> NOTE: All tests run in this JVM: [UpdateRequestProcessorFactoryTest, TestGroupingSearch, TestWordDelimiterFilterFactory, TestLMJelinekMercerSimilarityFactory, SpatialFilterTest, NumericFieldsTest, TestLMDirichletSimilarityFactory, TestCollationField, CopyFieldTest, TestPerFieldSimilarity, TestPseudoReturnFields, CoreContainerCoreInitFailuresTest, TermsComponentTest, DirectUpdateHandlerOptimizeTest, TermVectorComponentDistributedTest, SolrCoreCheckLockOnStartupTest, DirectSolrConnectionTest, DateMathParserTest, NotRequiredUniqueKeyTest, ResourceLoaderTest, TestFiltering, FieldAnalysisRequestHandlerTest, BadComponentTest, SuggesterFSTTest, LeaderElectionIntegrationTest, AnalysisAfterCoreReloadTest, TestBinaryResponseWriter, MinimalSchemaTest, DistributedTermsComponentTest, BinaryUpdateRequestHandlerTest, PluginInfoTest, CSVRequestHandlerTest, TestFastLRUCache, IndexBasedSpellCheckerTest, SolrCmdDistributorTest, SpellCheckComponentTest, TestIndexingPerformance, UniqFieldsUpdateProcessorFactoryTest, DistributedSpellCheckComponentTest, TestIndexSearcher, SpellPossibilityIteratorTest, QueryElevationComponentTest, SampleTest, SuggesterWFSTTest, SpellCheckCollatorTest, TestBadConfig, TestExtendedDismaxParser, TestLFUCache, ZkCLITest, TestRangeQuery, ClusterStateTest, TestSolrJ, PreAnalyzedFieldTest, SystemInfoHandlerTest, BasicDistributedZkTest]
[junit4:junit4] Completed on J0 in 379.93s, 1 test, 1 failure <<< FAILURES!

[...truncated 430 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/build.xml:29: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/solr/build.xml:146: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/module-build.xml:63: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:1132: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:809: There were test failures: 228 suites, 919 tests, 1 failure, 6 ignored (1 assumption)

Total time: 49 minutes 2 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