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 # 3198 - Still Failing
Date Thu, 13 Sep 2012 12:33:55 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-java7/3198/

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

Error Message:
Captured an uncaught exception in thread: Thread[id=1058, name=Overseer-88312314310492162-127.0.0.1:25231_solr-n_0000000000, state=RUNNABLE, group=Overseer collection creation process.]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1058, name=Overseer-88312314310492162-127.0.0.1:25231_solr-n_0000000000, state=RUNNABLE, group=Overseer collection creation process.]
Caused by: org.apache.solr.common.SolrException: java.lang.InterruptedException
	at org.apache.solr.handler.component.HttpShardHandler.take(HttpShardHandler.java:225)
	at org.apache.solr.handler.component.HttpShardHandler.takeCompletedOrError(HttpShardHandler.java:205)
	at org.apache.solr.cloud.OverseerCollectionProcessor.createCollection(OverseerCollectionProcessor.java:218)
	at org.apache.solr.cloud.OverseerCollectionProcessor.processMessage(OverseerCollectionProcessor.java:136)
	at org.apache.solr.cloud.OverseerCollectionProcessor.run(OverseerCollectionProcessor.java:90)
	at java.lang.Thread.run(Thread.java:722)
Caused by: java.lang.InterruptedException
	at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219)
	at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340)
	at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:439)
	at java.util.concurrent.ExecutorCompletionService.take(ExecutorCompletionService.java:193)
	at org.apache.solr.handler.component.HttpShardHandler.take(HttpShardHandler.java:212)
	... 5 more




Build Log:
[...truncated 8033 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 3 T1036 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-1347538975679
[junit4:junit4]   2> 4 T1036 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 8 T1037 oazs.ZooKeeperServer.setTickTime tickTime set to 3000
[junit4:junit4]   2> 8 T1037 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 9 T1037 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 106 T1036 oasc.ZkTestServer.run start zk server on port:25228
[junit4:junit4]   2> 106 T1036 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:25228 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@73e78de6
[junit4:junit4]   2> 107 T1042 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:25228
[junit4:junit4]   2> 107 T1036 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 108 T1042 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:25228, initiating session
[junit4:junit4]   2> 108 T1038 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:25229
[junit4:junit4]   2> 109 T1038 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:25229
[junit4:junit4]   2> 109 T1040 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 111 T1040 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bf94fbc50000 with negotiated timeout 10000 for client /140.211.11.196:25229
[junit4:junit4]   2> 112 T1042 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:25228, sessionid = 0x139bf94fbc50000, negotiated timeout = 10000
[junit4:junit4]   2> 112 T1043 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@73e78de6 name:ZooKeeperConnection Watcher:127.0.0.1:25228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 112 T1036 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 113 T1036 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 116 T1041 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139bf94fbc50000
[junit4:junit4]   2> 117 T1043 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 117 T1036 oaz.ZooKeeper.close Session: 0x139bf94fbc50000 closed
[junit4:junit4]   2> 118 T1038 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:25229 which had sessionid 0x139bf94fbc50000
[junit4:junit4]   2> 118 T1036 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:25228/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@3ca00148
[junit4:junit4]   2> 119 T1044 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:25228
[junit4:junit4]   2> 119 T1036 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 120 T1044 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:25228, initiating session
[junit4:junit4]   2> 120 T1038 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:25230
[junit4:junit4]   2> 120 T1038 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:25230
[junit4:junit4]   2> 127 T1040 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bf94fbc50001 with negotiated timeout 10000 for client /140.211.11.196:25230
[junit4:junit4]   2> 127 T1044 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:25228, sessionid = 0x139bf94fbc50001, negotiated timeout = 10000
[junit4:junit4]   2> 127 T1045 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3ca00148 name:ZooKeeperConnection Watcher:127.0.0.1:25228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 127 T1036 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 128 T1036 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 136 T1036 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 139 T1036 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 141 T1036 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 150 T1036 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> 151 T1036 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 168 T1036 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> 168 T1036 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 272 T1036 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> 273 T1036 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 276 T1036 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> 277 T1036 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 288 T1036 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> 288 T1036 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 291 T1036 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> 292 T1036 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 295 T1036 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> 296 T1036 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 299 T1036 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> 300 T1036 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 303 T1036 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> 304 T1036 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 307 T1036 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> 307 T1036 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 310 T1041 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139bf94fbc50001
[junit4:junit4]   2> 318 T1045 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 318 T1036 oaz.ZooKeeper.close Session: 0x139bf94fbc50001 closed
[junit4:junit4]   2> 318 T1038 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:25230 which had sessionid 0x139bf94fbc50001
[junit4:junit4]   2> 470 T1036 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 476 T1036 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:25231
[junit4:junit4]   2> 476 T1036 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 477 T1036 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> 477 T1036 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> 519 T1036 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 520 T1036 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 520 T1036 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> 521 T1036 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> 521 T1036 oasc.CoreContainer.<init> New CoreContainer 2138999781
[junit4:junit4]   2> 522 T1036 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> 522 T1036 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> 554 T1036 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 570 T1036 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:25228/solr
[junit4:junit4]   2> 571 T1036 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:25228/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@4510a478
[junit4:junit4]   2> 572 T1055 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:25228
[junit4:junit4]   2> 572 T1036 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 574 T1055 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:25228, initiating session
[junit4:junit4]   2> 574 T1038 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:25232
[junit4:junit4]   2> 574 T1038 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:25232
[junit4:junit4]   2> 576 T1040 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bf94fbc50002 with negotiated timeout 8000 for client /140.211.11.196:25232
[junit4:junit4]   2> 576 T1055 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:25228, sessionid = 0x139bf94fbc50002, negotiated timeout = 8000
[junit4:junit4]   2> 577 T1056 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4510a478 name:ZooKeeperConnection Watcher:127.0.0.1:25228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 577 T1036 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 579 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 590 T1036 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 593 T1036 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:25231_solr
[junit4:junit4]   2> 593 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:25231_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:25231_solr
[junit4:junit4]   2> 599 T1036 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:25231_solr
[junit4:junit4]   2> 603 T1036 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 604 T1036 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 604 T1036 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 604 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 605 T1036 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 605 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 606 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 606 T1036 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 607 T1036 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 607 T1036 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 625 T1036 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 638 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 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> 639 T1036 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 642 T1036 oasc.Overseer.start Overseer (id=88312314310492162-127.0.0.1:25231_solr-n_0000000000) starting
[junit4:junit4]   2> 643 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 644 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 646 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 647 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 648 T1058 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 649 T1036 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 660 T1036 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 662 T1057 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 666 T1036 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> 667 T1036 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 668 T1036 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 668 T1036 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 670 T1036 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> 671 T1036 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> 672 T1036 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> 722 T1036 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 792 T1036 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 893 T1036 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 901 T1036 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1542 T1036 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1554 T1036 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1558 T1036 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1573 T1036 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1579 T1036 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1584 T1036 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1585 T1036 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1587 T1036 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> 1587 T1036 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1311cccd
[junit4:junit4]   2> 1622 T1036 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> 1623 T1036 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> 1624 T1036 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> 1630 T1036 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@4b335328; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1631 T1036 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1632 T1036 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1634 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1635 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1636 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1636 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1637 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1638 T1036 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1638 T1036 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1639 T1036 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1640 T1036 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1640 T1036 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1641 T1036 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1642 T1036 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1642 T1036 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1643 T1036 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1643 T1036 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1644 T1036 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1645 T1036 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1645 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1646 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1647 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1647 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1648 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1649 T1036 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1649 T1036 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1650 T1036 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1654 T1036 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1659 T1036 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1660 T1036 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> 1664 T1036 oass.SolrIndexSearcher.<init> Opening Searcher@12b09902 main
[junit4:junit4]   2> 1666 T1036 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1666 T1036 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1667 T1036 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1707 T1036 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1846 T1059 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12b09902 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1851 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 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> 1861 T1036 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1862 T1036 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:25231/solr shardId:control_shard
[junit4:junit4]   2> 1863 T1036 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1877 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 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> 1887 T1036 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 1888 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1890 T1036 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:25231/solr/collection1/
[junit4:junit4]   2> 1890 T1036 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1895 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 type:create cxid:0x6f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2169 T1057 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2170 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50002 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> 2243 T1056 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2305 T1036 oasc.ZkController.register We are http://127.0.0.1:25231/solr/collection1/ and leader is http://127.0.0.1:25231/solr/collection1/
[junit4:junit4]   2> 2305 T1036 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:25231/solr
[junit4:junit4]   2> 2306 T1036 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2308 T1036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2309 T1036 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> 2309 T1036 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2310 T1036 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2478 T1036 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2481 T1036 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:25237
[junit4:junit4]   2> 2482 T1036 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2482 T1036 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> 2483 T1036 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> 2524 T1036 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2525 T1036 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2526 T1036 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> 2526 T1036 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> 2526 T1036 oasc.CoreContainer.<init> New CoreContainer 1623937722
[junit4:junit4]   2> 2527 T1036 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> 2528 T1036 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> 2560 T1036 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2575 T1036 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:25228/solr
[junit4:junit4]   2> 2576 T1036 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:25228/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@27618453
[junit4:junit4]   2> 2577 T1069 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:25228
[junit4:junit4]   2> 2577 T1036 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2578 T1069 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:25228, initiating session
[junit4:junit4]   2> 2579 T1038 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:25238
[junit4:junit4]   2> 2579 T1038 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:25238
[junit4:junit4]   2> 2581 T1040 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bf94fbc50003 with negotiated timeout 8000 for client /140.211.11.196:25238
[junit4:junit4]   2> 2581 T1069 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:25228, sessionid = 0x139bf94fbc50003, negotiated timeout = 8000
[junit4:junit4]   2> 2582 T1070 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@27618453 name:ZooKeeperConnection Watcher:127.0.0.1:25228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2582 T1036 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2583 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2596 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2598 T1036 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:25237_solr
[junit4:junit4]   2> 2598 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:25237_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:25237_solr
[junit4:junit4]   2> 2600 T1036 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:25237_solr
[junit4:junit4]   2> 2607 T1056 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2608 T1036 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2609 T1036 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2609 T1036 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2610 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2610 T1036 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2611 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2611 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2612 T1036 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2612 T1036 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2613 T1036 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2633 T1036 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2639 T1036 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> 2640 T1036 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2641 T1036 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2641 T1036 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2643 T1036 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> 2644 T1036 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> 2645 T1036 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> 2695 T1036 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2756 T1057 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2766 T1036 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2770 T1056 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2770 T1070 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2771 T1036 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2779 T1036 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3409 T1036 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3420 T1036 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3424 T1036 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3438 T1036 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3443 T1036 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3447 T1036 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3449 T1036 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3450 T1036 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> 3451 T1036 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1311cccd
[junit4:junit4]   2> 3452 T1036 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> 3453 T1036 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> 3453 T1036 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> 3486 T1036 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@5fd0ecf9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3486 T1036 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3487 T1036 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3490 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3490 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3491 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3492 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3492 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3493 T1036 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3494 T1036 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3494 T1036 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3495 T1036 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3496 T1036 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3497 T1036 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3497 T1036 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3498 T1036 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3498 T1036 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3499 T1036 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3500 T1036 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3500 T1036 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3501 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3502 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3502 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3503 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3504 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3505 T1036 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3505 T1036 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3506 T1036 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3510 T1036 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3514 T1036 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3516 T1036 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> 3519 T1036 oass.SolrIndexSearcher.<init> Opening Searcher@55bcff7c main
[junit4:junit4]   2> 3521 T1036 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3521 T1036 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3522 T1036 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3529 T1036 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3578 T1071 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@55bcff7c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3584 T1036 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3585 T1036 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:25237/solr shardId:shard3
[junit4:junit4]   2> 3586 T1036 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
[junit4:junit4]   2> 3599 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50003 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> 3600 T1036 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 3600 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50003 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3613 T1036 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:25237/solr/collection1/
[junit4:junit4]   2> 3613 T1036 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
[junit4:junit4]   2> 3618 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50003 type:create cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3777 T1057 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3804 T1056 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3804 T1070 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3834 T1036 oasc.ZkController.register We are http://127.0.0.1:25237/solr/collection1/ and leader is http://127.0.0.1:25237/solr/collection1/
[junit4:junit4]   2> 3834 T1036 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:25237/solr
[junit4:junit4]   2> 3835 T1036 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3837 T1036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3838 T1036 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> 3838 T1036 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3839 T1036 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4009 T1036 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4013 T1036 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:25242
[junit4:junit4]   2> 4013 T1036 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4014 T1036 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> 4014 T1036 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> 4057 T1036 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4058 T1036 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4058 T1036 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> 4059 T1036 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> 4059 T1036 oasc.CoreContainer.<init> New CoreContainer 136745180
[junit4:junit4]   2> 4060 T1036 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> 4060 T1036 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 T1036 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4109 T1036 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:25228/solr
[junit4:junit4]   2> 4109 T1036 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:25228/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@244eb220
[junit4:junit4]   2> 4110 T1081 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:25228
[junit4:junit4]   2> 4110 T1036 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4112 T1081 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:25228, initiating session
[junit4:junit4]   2> 4112 T1038 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:25244
[junit4:junit4]   2> 4112 T1038 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:25244
[junit4:junit4]   2> 4140 T1040 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bf94fbc50004 with negotiated timeout 8000 for client /140.211.11.196:25244
[junit4:junit4]   2> 4140 T1081 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:25228, sessionid = 0x139bf94fbc50004, negotiated timeout = 8000
[junit4:junit4]   2> 4140 T1082 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@244eb220 name:ZooKeeperConnection Watcher:127.0.0.1:25228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4140 T1036 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4141 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4153 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4154 T1036 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:25242_solr
[junit4:junit4]   2> 4155 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:25242_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:25242_solr
[junit4:junit4]   2> 4161 T1036 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:25242_solr
[junit4:junit4]   2> 4163 T1056 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4163 T1070 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4164 T1036 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4164 T1036 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4165 T1036 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4165 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4166 T1036 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4166 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4167 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4167 T1036 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4168 T1036 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4168 T1036 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4188 T1036 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4195 T1036 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> 4195 T1036 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4196 T1036 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4197 T1036 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4198 T1036 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> 4199 T1036 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> 4200 T1036 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> 4250 T1036 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4319 T1057 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4320 T1036 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4332 T1056 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4332 T1070 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4422 T1082 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4422 T1036 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4430 T1036 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5052 T1036 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5063 T1036 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5067 T1036 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5081 T1036 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5087 T1036 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5091 T1036 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5093 T1036 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5094 T1036 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> 5095 T1036 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1311cccd
[junit4:junit4]   2> 5096 T1036 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> 5097 T1036 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> 5098 T1036 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> 5131 T1036 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@5d7dc8ed; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5132 T1036 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5133 T1036 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5136 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5136 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5137 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5137 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5138 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5139 T1036 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5139 T1036 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5140 T1036 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5141 T1036 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5142 T1036 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5143 T1036 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5143 T1036 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5144 T1036 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5144 T1036 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5145 T1036 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5146 T1036 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5147 T1036 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5147 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5148 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5149 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5150 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5150 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5151 T1036 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5152 T1036 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5153 T1036 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5156 T1036 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5161 T1036 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5163 T1036 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> 5167 T1036 oass.SolrIndexSearcher.<init> Opening Searcher@493d1f37 main
[junit4:junit4]   2> 5168 T1036 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5169 T1036 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5170 T1036 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5177 T1036 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5235 T1083 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@493d1f37 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5241 T1036 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5242 T1036 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:25242/solr shardId:shard4
[junit4:junit4]   2> 5243 T1036 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard4/election
[junit4:junit4]   2> 5257 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50004 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> 5258 T1036 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 5258 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50004 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5266 T1036 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:25242/solr/collection1/
[junit4:junit4]   2> 5267 T1036 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard4
[junit4:junit4]   2> 5270 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50004 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5338 T1057 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5358 T1056 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5370 T1082 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5370 T1070 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5382 T1036 oasc.ZkController.register We are http://127.0.0.1:25242/solr/collection1/ and leader is http://127.0.0.1:25242/solr/collection1/
[junit4:junit4]   2> 5382 T1036 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:25242/solr
[junit4:junit4]   2> 5383 T1036 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 5385 T1036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5386 T1036 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> 5386 T1036 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5387 T1036 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5552 T1036 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5555 T1036 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:25245
[junit4:junit4]   2> 5556 T1036 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5557 T1036 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> 5557 T1036 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> 5599 T1036 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5599 T1036 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5600 T1036 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> 5600 T1036 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> 5601 T1036 oasc.CoreContainer.<init> New CoreContainer 1782326297
[junit4:junit4]   2> 5601 T1036 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> 5602 T1036 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> 5634 T1036 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5650 T1036 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:25228/solr
[junit4:junit4]   2> 5650 T1036 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:25228/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@dba2f07
[junit4:junit4]   2> 5651 T1093 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:25228
[junit4:junit4]   2> 5651 T1036 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5653 T1093 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:25228, initiating session
[junit4:junit4]   2> 5653 T1038 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:25246
[junit4:junit4]   2> 5653 T1038 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:25246
[junit4:junit4]   2> 5656 T1040 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bf94fbc50005 with negotiated timeout 8000 for client /140.211.11.196:25246
[junit4:junit4]   2> 5656 T1093 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:25228, sessionid = 0x139bf94fbc50005, negotiated timeout = 8000
[junit4:junit4]   2> 5656 T1094 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@dba2f07 name:ZooKeeperConnection Watcher:127.0.0.1:25228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5656 T1036 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5657 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5669 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5670 T1036 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:25245_solr
[junit4:junit4]   2> 5671 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:25245_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:25245_solr
[junit4:junit4]   2> 5672 T1036 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:25245_solr
[junit4:junit4]   2> 5675 T1056 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5675 T1070 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5675 T1082 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5676 T1036 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5676 T1036 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5677 T1036 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5677 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5678 T1036 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5678 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5679 T1036 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5679 T1036 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5680 T1036 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5680 T1036 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5698 T1036 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5704 T1036 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> 5705 T1036 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5706 T1036 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5706 T1036 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5707 T1036 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> 5709 T1036 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> 5710 T1036 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> 5760 T1036 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5830 T1036 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5877 T1057 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5883 T1070 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5883 T1082 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5883 T1056 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5931 T1094 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5931 T1036 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5939 T1036 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6571 T1036 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6583 T1036 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6587 T1036 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6602 T1036 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6607 T1036 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6612 T1036 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6614 T1036 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6615 T1036 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> 6616 T1036 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1311cccd
[junit4:junit4]   2> 6617 T1036 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> 6618 T1036 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> 6619 T1036 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> 6703 T1036 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@1bf55784; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6703 T1036 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6705 T1036 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6707 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6708 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6708 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6709 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6710 T1036 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6710 T1036 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6711 T1036 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6712 T1036 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6713 T1036 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6713 T1036 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6714 T1036 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6715 T1036 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6716 T1036 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6716 T1036 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6717 T1036 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6718 T1036 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6718 T1036 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6719 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6720 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6721 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6721 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6722 T1036 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6723 T1036 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6724 T1036 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6724 T1036 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6728 T1036 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6733 T1036 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6735 T1036 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> 6739 T1036 oass.SolrIndexSearcher.<init> Opening Searcher@162ec222 main
[junit4:junit4]   2> 6740 T1036 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6741 T1036 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6741 T1036 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6771 T1036 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6838 T1095 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@162ec222 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6845 T1036 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6846 T1036 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:25245/solr shardId:shard5
[junit4:junit4]   2> 6846 T1036 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard5/election
[junit4:junit4]   2> 6860 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50005 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> 6861 T1036 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 6862 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50005 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6864 T1036 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:25245/solr/collection1/
[junit4:junit4]   2> 6865 T1036 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard5
[junit4:junit4]   2> 6868 T1041 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139bf94fbc50005 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6896 T1057 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6907 T1070 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6907 T1082 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6907 T1056 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6907 T1094 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6921 T1036 oasc.ZkController.register We are http://127.0.0.1:25245/solr/collection1/ and leader is http://127.0.0.1:25245/solr/collection1/
[junit4:junit4]   2> 6921 T1036 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:25245/solr
[junit4:junit4]   2> 6922 T1036 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 6924 T1036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6925 T1036 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> 6925 T1036 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6926 T1036 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C50 name=collection1 org.apache.solr.core.SolrCore@14fe2ecb url=http://127.0.0.1:25231/solr/collection1 node=127.0.0.1:25231_solr
[junit4:junit4]   2> 6955 T1048 C50 P25231 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@4b335328; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6956 T1048 C50 P25231 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6958 T1048 C50 P25231 oass.SolrIndexSearcher.<init> Opening Searcher@6b10f609 realtime
[junit4:junit4]   2> 6958 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412997036246040576)} 0 7
[junit4:junit4]   2> ASYNC  NEW_CORE C51 name=collection1 org.apache.solr.core.SolrCore@4a239a23 url=http://127.0.0.1:25237/solr/collection1 node=127.0.0.1:25237_solr
[junit4:junit4]   2> 6970 T1062 C51 P25237 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@5fd0ecf9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6971 T1062 C51 P25237 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6972 T1062 C51 P25237 oass.SolrIndexSearcher.<init> Opening Searcher@28c91ac6 realtime
[junit4:junit4]   2> ASYNC  NEW_CORE C52 name=collection1 org.apache.solr.core.SolrCore@14cf32ed url=http://127.0.0.1:25242/solr/collection1 node=127.0.0.1:25242_solr
[junit4:junit4]   2> 6984 T1074 C52 P25242 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@5d7dc8ed; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> ASYNC  NEW_CORE C53 name=collection1 org.apache.solr.core.SolrCore@3c42169f url=http://127.0.0.1:25245/solr/collection1 node=127.0.0.1:25245_solr
[junit4:junit4]   2> 6984 T1086 C53 P25245 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@1bf55784; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6985 T1086 C53 P25245 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6985 T1074 C52 P25242 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6987 T1086 C53 P25245 oass.SolrIndexSearcher.<init> Opening Searcher@34a5199d realtime
[junit4:junit4]   2> 6987 T1074 C52 P25242 oass.SolrIndexSearcher.<init> Opening Searcher@37656e45 realtime
[junit4:junit4]   2> 6987 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 6988 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 6988 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412997036261769216)} 0 22
[junit4:junit4]   2> 6995 T1063 C51 P25237 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6996 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 6997 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412997036287983616)} 0 6
[junit4:junit4]   2> 7004 T1063 C51 P25237 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7004 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7005 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412997036297420800)} 0 6
[junit4:junit4]   2> 7012 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1412997036306857984)]} 0 4
[junit4:junit4]   2> 7018 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1412997036313149440)]} 0 3
[junit4:junit4]   2> 7024 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1412997036320489472)]} 0 2
[junit4:junit4]   2> 7030 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1412997036325732352)]} 0 3
[junit4:junit4]   2> 7035 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3 (1412997036332023808)]} 0 2
[junit4:junit4]   2> 7049 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7050 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 12
[junit4:junit4]   2> 7055 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4 (1412997036352995328)]} 0 2
[junit4:junit4]   2> 7063 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7064 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 6
[junit4:junit4]   2> 7069 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5 (1412997036367675392)]} 0 2
[junit4:junit4]   2> 7078 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 7079 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 7
[junit4:junit4]   2> 7084 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6 (1412997036383404032)]} 0 2
[junit4:junit4]   2> 7092 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7093 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 6
[junit4:junit4]   2> 7098 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7 (1412997036398084096)]} 0 2
[junit4:junit4]   2> 7106 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7107 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 6
[junit4:junit4]   2> 7112 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8 (1412997036412764160)]} 0 2
[junit4:junit4]   2> 7120 T1063 C51 P25237 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7121 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 6
[junit4:junit4]   2> 7126 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9 (1412997036427444224)]} 0 2
[junit4:junit4]   2> 7134 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7135 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 6
[junit4:junit4]   2> 7153 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10 (1412997036442124288)]} 0 15
[junit4:junit4]   2> 7158 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10 (1412997036460998656)]} 0 2
[junit4:junit4]   2> 7164 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11 (1412997036466241536)]} 0 3
[junit4:junit4]   2> 7186 T1063 C51 P25237 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=16 
[junit4:junit4]   2> 7188 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 21
[junit4:junit4]   2> 7193 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12 (1412997036498747392)]} 0 2
[junit4:junit4]   2> 7201 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7202 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 6
[junit4:junit4]   2> 7207 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13 (1412997036512378880)]} 0 2
[junit4:junit4]   2> 7213 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13 (1412997036517621760)]} 0 3
[junit4:junit4]   2> 7218 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14 (1412997036523913216)]} 0 2
[junit4:junit4]   2> 7226 T1063 C51 P25237 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7227 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 6
[junit4:junit4]   2> 7232 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15 (1412997036538593280)]} 0 2
[junit4:junit4]   2> 7240 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7241 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 6
[junit4:junit4]   2> 7246 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16 (1412997036554321920)]} 0 2
[junit4:junit4]   2> 7251 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16 (1412997036558516224)]} 0 2
[junit4:junit4]   2> 7259 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17 (1412997036564807680)]} 0 5
[junit4:junit4]   2> 7271 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 7272 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 10
[junit4:junit4]   2> 7277 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100 (1412997036585779200)]} 0 2
[junit4:junit4]   2> 7285 T1063 C51 P25237 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7286 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 6
[junit4:junit4]   2> 7291 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101 (1412997036600459264)]} 0 2
[junit4:junit4]   2> 7298 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7298 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 5
[junit4:junit4]   2> 7316 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102 (1412997036613042176)]} 0 15
[junit4:junit4]   2> 7336 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=14 
[junit4:junit4]   2> 7337 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 18
[junit4:junit4]   2> 7343 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103 (1412997036653936640)]} 0 3
[junit4:junit4]   2> 7350 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7350 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 5
[junit4:junit4]   2> 7355 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104 (1412997036667568128)]} 0 2
[junit4:junit4]   2> 7359 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104 (1412997036671762432)]} 0 2
[junit4:junit4]   2> 7364 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105 (1412997036677005312)]} 0 2
[junit4:junit4]   2> 7372 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 7372 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 6
[junit4:junit4]   2> 7377 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106 (1412997036690636800)]} 0 2
[junit4:junit4]   2> 7384 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7384 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 5
[junit4:junit4]   2> 7389 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107 (1412997036703219712)]} 0 2
[junit4:junit4]   2> 7396 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7396 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 5
[junit4:junit4]   2> 7401 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108 (1412997036715802624)]} 0 2
[junit4:junit4]   2> 7405 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108 (1412997036719996928)]} 0 2
[junit4:junit4]   2> 7410 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109 (1412997036725239808)]} 0 2
[junit4:junit4]   2> 7413 T1057 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7429 T1063 C51 P25237 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=14 
[junit4:junit4]   2> 7430 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 18
[junit4:junit4]   2> 7435 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110 (1412997036751454208)]} 0 2
[junit4:junit4]   2> 7441 T1094 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7441 T1070 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7442 T1056 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7441 T1082 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7443 T1063 C51 P25237 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2>  C53_STATE=coll:collection1 core:collection1 props:{shard=shard5, leader=true, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:25245_solr, base_url=http://127.0.0.1:25245/solr}
[junit4:junit4]   2> 7443 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 6
[junit4:junit4]   2> 7449 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1412997036766134272)]} 0 2
[junit4:junit4]   2> 7456 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7457 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 6
[junit4:junit4]   2> 7474 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112 (1412997036779765760)]} 0 14
[junit4:junit4]   2> 7481 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7481 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 5
[junit4:junit4]   2> 7487 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113 (1412997036804931584)]} 0 3
[junit4:junit4]   2> 7494 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7494 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 5
[junit4:junit4]   2> 7499 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114 (1412997036818563072)]} 0 2
[junit4:junit4]   2> 7506 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7506 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 5
[junit4:junit4]   2> 7511 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115 (1412997036831145984)]} 0 2
[junit4:junit4]   2> 7515 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115 (1412997036835340288)]} 0 2
[junit4:junit4]   2> 7520 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116 (1412997036840583168)]} 0 2
[junit4:junit4]   2> 7525 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116 (1412997036845826048)]} 0 2
[junit4:junit4]   2> 7530 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117 (1412997036851068928)]} 0 2
[junit4:junit4]   2> 7537 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7537 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 5
[junit4:junit4]   2> 7542 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118 (1412997036863651840)]} 0 2
[junit4:junit4]   2> 7549 T1063 C51 P25237 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7549 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 5
[junit4:junit4]   2> 7554 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119 (1412997036876234752)]} 0 2
[junit4:junit4]   2> 7561 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7561 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 5
[junit4:junit4]   2> 7566 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120 (1412997036888817664)]} 0 2
[junit4:junit4]   2> 7573 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7573 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 5
[junit4:junit4]   2> 7578 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121 (1412997036901400576)]} 0 2
[junit4:junit4]   2> 7585 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7585 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 5
[junit4:junit4]   2> 7602 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122 (1412997036913983488)]} 0 14
[junit4:junit4]   2> 7622 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=14 
[junit4:junit4]   2> 7623 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 18
[junit4:junit4]   2> 7629 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1412997036954877952)]} 0 3
[junit4:junit4]   2> 7634 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1412997036961169408)]} 0 2
[junit4:junit4]   2> 7639 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124 (1412997036966412288)]} 0 2
[junit4:junit4]   2> 7647 T1063 C51 P25237 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7648 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 6
[junit4:junit4]   2> 7653 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125 (1412997036980043776)]} 0 2
[junit4:junit4]   2> 7661 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 7661 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 6
[junit4:junit4]   2> 7667 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126 (1412997036995772416)]} 0 2
[junit4:junit4]   2> 7675 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7676 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 6
[junit4:junit4]   2> 7681 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127 (1412997037010452480)]} 0 2
[junit4:junit4]   2> 7702 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=15 
[junit4:junit4]   2> 7703 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 19
[junit4:junit4]   2> 7708 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128 (1412997037038764032)]} 0 2
[junit4:junit4]   2> 7714 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128 (1412997037044006912)]} 0 3
[junit4:junit4]   2> 7719 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129 (1412997037049249792)]} 0 2
[junit4:junit4]   2> 7727 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7727 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 5
[junit4:junit4]   2> 7733 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130 (1412997037063929856)]} 0 2
[junit4:junit4]   2> 7738 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130 (1412997037070221312)]} 0 2
[junit4:junit4]   2> 7743 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131 (1412997037075464192)]} 0 2
[junit4:junit4]   2> 7748 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131 (1412997037079658496)]} 0 2
[junit4:junit4]   2> 7766 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132 (1412997037085949952)]} 0 15
[junit4:junit4]   2> 7771 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132 (1412997037104824320)]} 0 2
[junit4:junit4]   2> 7777 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133 (1412997037110067200)]} 0 3
[junit4:junit4]   2> 7782 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133 (1412997037116358656)]} 0 2
[junit4:junit4]   2> 7787 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134 (1412997037121601536)]} 0 2
[junit4:junit4]   2> 7796 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 7797 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 7
[junit4:junit4]   2> 7801 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135 (1412997037136281600)]} 0 1
[junit4:junit4]   2> 7805 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135 (1412997037140475904)]} 0 1
[junit4:junit4]   2> 7809 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136 (1412997037144670208)]} 0 1
[junit4:junit4]   2> 7816 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7817 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 5
[junit4:junit4]   2> 7821 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137 (1412997037157253120)]} 0 1
[junit4:junit4]   2> 7825 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137 (1412997037161447424)]} 0 1
[junit4:junit4]   2> 7829 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138 (1412997037165641728)]} 0 1
[junit4:junit4]   2> 7833 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138 (1412997037169836032)]} 0 1
[junit4:junit4]   2> 7837 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139 (1412997037174030336)]} 0 1
[junit4:junit4]   2> 7844 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7845 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139]} 0 5
[junit4:junit4]   2> 7849 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140 (1412997037186613248)]} 0 1
[junit4:junit4]   2> 7856 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7857 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 5
[junit4:junit4]   2> 7862 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141 (1412997037200244736)]} 0 2
[junit4:junit4]   2> 7869 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7870 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141]} 0 5
[junit4:junit4]   2> 7887 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142 (1412997037213876224)]} 0 14
[junit4:junit4]   2> 7894 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7895 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142]} 0 5
[junit4:junit4]   2> 7900 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143 (1412997037239042048)]} 0 2
[junit4:junit4]   2> 7907 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7908 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143]} 0 5
[junit4:junit4]   2> 7912 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144 (1412997037252673536)]} 0 1
[junit4:junit4]   2> 7919 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7920 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 5
[junit4:junit4]   2> 7924 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145 (1412997037265256448)]} 0 1
[junit4:junit4]   2> 7931 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7932 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 5
[junit4:junit4]   2> 7936 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146 (1412997037277839360)]} 0 1
[junit4:junit4]   2> 7955 T1074 C52 P25242 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25245/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=13 
[junit4:junit4]   2> 7956 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 17
[junit4:junit4]   2> 7960 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147 (1412997037303005184)]} 0 1
[junit4:junit4]   2> 7967 T1086 C53 P25245 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25237/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7968 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 5
[junit4:junit4]   2> 7972 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148 (1412997037315588096)]} 0 1
[junit4:junit4]   2> 7979 T1063 C51 P25237 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:25242/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7980 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148]} 0 5
[junit4:junit4]   2> 7984 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149 (1412997037328171008)]} 0 1
[junit4:junit4]   2> 7988 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149 (1412997037332365312)]} 0 1
[junit4:junit4]   2> 7991 T1048 C50 P25231 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8441 T1048 C50 P25231 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@4b335328; 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@4b335328; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_5_nrm.cfe, _3_MockVariableIntBlock_0.frq, _4_MockSep_0.skp, _3.fdt, _4_MockVariableIntBlock_0.tii, _2_MockSep_0.doc, _3_Lucene40_0.frq, _6_MockSep_0.doc, _5_MockSep_0.skp, _3_TestBloomFilteredLucene40Postings_0.frq, _6_MockSep_0.frq, _6_MockVariableIntBlock_0.tii, _1_MockSep_0.pyl, _3_TestBloomFilteredLucene40Postings_0.tim, _0_Lucene40_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _6_MockVariableIntBlock_0.tib, _1_MockSep_0.tib, _1_MockVariableIntBlock_0.tii, _3_TestBloomFilteredLucene40Postings_0.tip, _5_MockSep_0.doc, _1_MockVariableIntBlock_0.tib, _1_MockSep_0.tii, _0_Lucene40_0.tip, _5_TestBloomFilteredLucene40Postings_0.frq, _1.fdx, _4_MockVariableIntBlock_0.tib, _1.fdt, _3_MockSep_0.pos, _5_nrm.cfs, _0_MockVariableIntBlock_0.frq, _6.fdt, _2.si, _1_MockVariableIntBlock_0.skp, _0_MockVariableIntBlock_0.doc, _6_MockSep_0.pyl, _2_MockSep_0.skp, _6.fdx, _1_MockVariableIntBlock_0.pyl, _5_MockSep_0.tii, _3_MockVariableIntBlock_0.tii, _2_nrm.cfe, _0_MockVariableIntBlock_0.pos, _2_nrm.cfs, _2_TestBloomFilteredLucene40Postings_0.frq, _4_TestBloomFilteredLucene40Postings_0.blm, _4_MockVariableIntBlock_0.frq, _2.fnm, _5_MockVariableIntBlock_0.frq, _3.fdx, _2_MockSep_0.pyl, _1.si, _5_MockSep_0.tib, _4_TestBloomFilteredLucene40Postings_0.tim, _3_MockSep_0.skp, _4_TestBloomFilteredLucene40Postings_0.tip, _5_Lucene40_0.prx, _3_MockVariableIntBlock_0.pos, _3_MockSep_0.doc, _3_nrm.cfe, _5_MockVariableIntBlock_0.pyl, _4_nrm.cfe, _4.fdx, _6_MockVariableIntBlock_0.frq, _6_MockSep_0.tii, _1_MockSep_0.doc, _2_TestBloomFilteredLucene40Postings_0.tim, _6_MockSep_0.tib, _3_MockVariableIntBlock_0.doc, _6_Lucene40_0.frq, _2_TestBloomFilteredLucene40Postings_0.tip, _1_TestBloomFilteredLucene40Postings_0.frq, _1_Lucene40_0.prx, _5.si, _3_nrm.cfs, _4_MockVariableIntBlock_0.pyl, _4_nrm.cfs, _3_MockVariableIntBlock_0.tib, _6_Lucene40_0.prx, _5_MockSep_0.pyl, _5_MockVariableIntBlock_0.doc, _6_MockVariableIntBlock_0.pyl, _0_TestBloomFilteredLucene40Postings_0.prx, _2_MockVariableIntBlock_0.frq, _6_nrm.cfs, _3_Lucene40_0.prx, _6_TestBloomFilteredLucene40Postings_0.tip, _0_MockSep_0.doc, _6_TestBloomFilteredLucene40Postings_0.tim, _6_TestBloomFilteredLucene40Postings_0.frq, _1_nrm.cfs, _5.fnm, _1_TestBloomFilteredLucene40Postings_0.prx, _6_nrm.cfe, _2_MockSep_0.frq, _4.fdt, _4.si, _1_nrm.cfe, _0_MockSep_0.frq, _5_TestBloomFilteredLucene40Postings_0.tim, _5_TestBloomFilteredLucene40Postings_0.tip, _4_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.prx, _0_MockSep_0.pos, _4_MockSep_0.tib, _2_TestBloomFilteredLucene40Postings_0.blm, _3_MockSep_0.frq, _0_Lucene40_0.prx, _4_MockSep_0.doc, _0_TestBloomFilteredLucene40Postings_0.frq, _5_MockVariableIntBlock_0.tii, _1_MockSep_0.skp, _4_MockVariableIntBlock_0.doc, _2.fdt, _2.fdx, _4_MockSep_0.pyl, _2_Lucene40_0.prx, _3.si, _2_MockVariableIntBlock_0.skp, _3_TestBloomFilteredLucene40Postings_0.blm, _4_MockSep_0.tii, _2_MockVariableIntBlock_0.pyl, _3.fnm, _3_MockSep_0.pyl, _2_MockVariableIntBlock_0.doc, _4_TestBloomFilteredLucene40Postings_0.prx, _0_MockVariableIntBlock_0.pyl, _5_Lucene40_0.tim, _0_Lucene40_0.frq, _5_Lucene40_0.tip, _1_MockVariableIntBlock_0.doc, _5_TestBloomFilteredLucene40Postings_0.blm, _6_MockSep_0.pos, _2_MockVariableIntBlock_0.tii, _3_Lucene40_0.tip, _3_Lucene40_0.tim, _1_MockSep_0.pos, _5_MockVariableIntBlock_0.tib, _2_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.pos, _1_Lucene40_0.frq, _6_MockSep_0.skp, _2_Lucene40_0.frq, _3_MockVariableIntBlock_0.pyl, _6.si, _6.fnm, _1_Lucene40_0.tim, _5_MockSep_0.pos, _5.fdt, _6_Lucene40_0.tim, _2_TestBloomFilteredLucene40Postings_0.prx, _6_Lucene40_0.tip, _4.fnm, _0_MockVariableIntBlock_0.skp, _1.fnm, _1_Lucene40_0.tip, _2_Lucene40_0.tip, _0_MockSep_0.skp, _3_MockSep_0.tib, _5_TestBloomFilteredLucene40Postings_0.prx, _2_Lucene40_0.tim, _6_MockVariableIntBlock_0.doc, _2_MockVariableIntBlock_0.pos, _0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _5_Lucene40_0.frq, _0.fnm, _5_MockVariableIntBlock_0.pos, _4_MockSep_0.pos, _5_MockSep_0.frq, _2_MockSep_0.tii, _2_MockSep_0.tib, _3_MockSep_0.tii, _4_Lucene40_0.frq, _2_MockSep_0.pos, _1_MockSep_0.frq, _1_TestBloomFilteredLucene40Postings_0.blm, _1_TestBloomFilteredLucene40Postings_0.tip, _4_MockVariableIntBlock_0.skp, _0_MockVariableIntBlock_0.tib, _5_MockVariableIntBlock_0.skp, _4_MockVariableIntBlock_0.pos, _6_MockVariableIntBlock_0.pos, _4_Lucene40_0.tip, _0.si, _1_TestBloomFilteredLucene40Postings_0.tim, _4_Lucene40_0.tim, _0_MockVariableIntBlock_0.tii, _4_Lucene40_0.prx, _0_nrm.cfs, _6_MockVariableIntBlock_0.skp, _0_MockSep_0.tib, _1_MockVariableIntBlock_0.frq, _0_nrm.cfe, segments_2, _4_MockSep_0.frq, _5.fdx, _6_TestBloomFilteredLucene40Postings_0.blm, _0.fdx, _6_TestBloomFilteredLucene40Postings_0.prx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _3_MockVariableIntBlock_0.skp, _0.fdt]
[junit4:junit4]   2> 8443 T1048 C50 P25231 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 8456 T1048 C50 P25231 oass.SolrIndexSearcher.<init> Opening Searcher@40fb9f14 main
[junit4:junit4]   2> 8457 T1048 C50 P25231 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8458 T1059 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@40fb9f14 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> 8459 T1048 C50 P25231 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 468
[junit4:junit4]   2> 8462 T1062 C51 P25237 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8618 T1062 C51 P25237 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@5fd0ecf9; 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@5fd0ecf9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_1_Lucene40_0.tim, _0_MockSep_0.pos, _0_MockVariableIntBlock_0.skp, _0_Lucene40_0.prx, _1.fnm, _1_Lucene40_0.tip, _0_MockSep_0.skp, _0_TestBloomFilteredLucene40Postings_0.frq, _1_MockSep_0.pyl, _1_MockSep_0.doc, _0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0_Lucene40_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _1_MockSep_0.skp, _0.fnm, _1_MockSep_0.tib, _1_MockVariableIntBlock_0.tii, _1_TestBloomFilteredLucene40Postings_0.frq, _1_Lucene40_0.prx, _1_MockVariableIntBlock_0.tib, _1_MockSep_0.tii, _0_Lucene40_0.tip, _1.fdx, _1.fdt, _0_MockVariableIntBlock_0.frq, _1_MockSep_0.frq, _1_MockVariableIntBlock_0.skp, _0_MockVariableIntBlock_0.doc, _0_TestBloomFilteredLucene40Postings_0.prx, _1_TestBloomFilteredLucene40Postings_0.blm, _1_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.pyl, _1_TestBloomFilteredLucene40Postings_0.tip, _0_Lucene40_0.frq, _0_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _0.si, _0_MockSep_0.doc, _1_TestBloomFilteredLucene40Postings_0.tim, _0_MockVariableIntBlock_0.tii, _1_nrm.cfs, _1_TestBloomFilteredLucene40Postings_0.prx, _1_MockSep_0.pos, _1_MockVariableIntBlock_0.pos, _1_Lucene40_0.frq, _0_nrm.cfs, _1_nrm.cfe, _0_MockSep_0.tib, _1_MockVariableIntBlock_0.frq, _1.si, _0_nrm.cfe, segments_2, _0_MockSep_0.frq, _0.fdx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _0.fdt]
[junit4:junit4]   2> 8619 T1062 C51 P25237 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 8631 T1062 C51 P25237 oass.SolrIndexSearcher.<init> Opening Searcher@6084c675 main
[junit4:junit4]   2> 8632 T1062 C51 P25237 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8633 T1071 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6084c675 main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C8)}
[junit4:junit4]   2> 8634 T1062 C51 P25237 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:25242/solr/collection1/, StdNode: http://127.0.0.1:25245/solr/collection1/]
[junit4:junit4]   2> 8638 T1074 C52 P25242 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8638 T1086 C53 P25245 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8941 T1086 C53 P25245 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@1bf55784; 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@1bf55784; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_1_Lucene40_0.tim, _0_MockSep_0.pos, _0_MockVariableIntBlock_0.skp, _0_Lucene40_0.prx, _1.fnm, _1_Lucene40_0.tip, _0_MockSep_0.skp, _0_TestBloomFilteredLucene40Postings_0.frq, _1_MockSep_0.pyl, _1_MockSep_0.doc, _0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0_Lucene40_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _1_MockSep_0.skp, _0.fnm, _1_MockSep_0.tib, _1_MockVariableIntBlock_0.tii, _1_TestBloomFilteredLucene40Postings_0.frq, _1_Lucene40_0.prx, _1_MockVariableIntBlock_0.tib, _1_MockSep_0.tii, _0_Lucene40_0.tip, _1.fdx, _1.fdt, _0_MockVariableIntBlock_0.frq, _1_MockSep_0.frq, _1_MockVariableIntBlock_0.skp, _0_MockVariableIntBlock_0.doc, _0_TestBloomFilteredLucene40Postings_0.prx, _1_TestBloomFilteredLucene40Postings_0.blm, _1_MockVariableIntBlock_0.pyl, _0_MockVariableIntBlock_0.pyl, _1_TestBloomFilteredLucene40Postings_0.tip, _0_Lucene40_0.frq, _0_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _0.si, _0_MockSep_0.doc, _1_TestBloomFilteredLucene40Postings_0.tim, _0_MockVariableIntBlock_0.tii, _1_nrm.cfs, _1_TestBloomFilteredLucene40Postings_0.prx, _1_MockSep_0.pos, _1_MockVariableIntBlock_0.pos, _1_Lucene40_0.frq, _0_nrm.cfs, _1_nrm.cfe, _0_MockSep_0.tib, _1_MockVariableIntBlock_0.frq, _1.si, _0_nrm.cfe, segments_2, _0_MockSep_0.frq, _0.fdx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _0.fdt]
[junit4:junit4]   2> 8942 T1086 C53 P25245 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 8954 T1086 C53 P25245 oass.SolrIndexSearcher.<init> Opening Searcher@557290a0 main
[junit4:junit4]   2> 8954 T1086 C53 P25245 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8955 T1095 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@557290a0 main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 8956 T1086 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 318
[junit4:junit4]   2> 9022 T1074 C52 P25242 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@5d7dc8ed; 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@5d7dc8ed; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_1_Lucene40_0.tim, _2_TestBloomFilteredLucene40Postings_0.prx, _0_MockSep_0.pos, _2_TestBloomFilteredLucene40Postings_0.blm, _0_MockVariableIntBlock_0.skp, _0_Lucene40_0.prx, _2_MockSep_0.doc, _1.fnm, _1_Lucene40_0.tip, _2_Lucene40_0.tip, _0_MockSep_0.skp, _0_TestBloomFilteredLucene40Postings_0.frq, _2_Lucene40_0.tim, _2_MockVariableIntBlock_0.pos, _1_MockSep_0.pyl, _1_MockSep_0.doc, _2_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0_Lucene40_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _1_MockSep_0.skp, _0.fnm, _1_MockSep_0.tib, _1_MockVariableIntBlock_0.tii, _2.fdt, _2_MockSep_0.tii, _2_MockSep_0.tib, _2.fdx, _2_TestBloomFilteredLucene40Postings_0.tip, _1_TestBloomFilteredLucene40Postings_0.frq, _1_Lucene40_0.prx, _1_MockVariableIntBlock_0.tib, _1_MockSep_0.tii, _0_Lucene40_0.tip, _1.fdx, _1.fdt, _2_Lucene40_0.prx, _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, _0_TestBloomFilteredLucene40Postings_0.prx, _2_MockVariableIntBlock_0.frq, _1_TestBloomFilteredLucene40Postings_0.blm, _1_MockVariableIntBlock_0.pyl, _2_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pyl, _1_TestBloomFilteredLucene40Postings_0.tip, _2_nrm.cfe, _0_Lucene40_0.frq, _0_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.doc, _0_MockVariableIntBlock_0.pos, _2_nrm.cfs, _0.si, _0_MockSep_0.doc, _1_TestBloomFilteredLucene40Postings_0.tim, _0_MockVariableIntBlock_0.tii, _2_TestBloomFilteredLucene40Postings_0.frq, _2_MockVariableIntBlock_0.tii, _1_nrm.cfs, _1_TestBloomFilteredLucene40Postings_0.prx, _1_MockSep_0.pos, _2_MockVariableIntBlock_0.tib, _1_MockVariableIntBlock_0.pos, _1_Lucene40_0.frq, _2_MockSep_0.frq, _0_nrm.cfs, _2.fnm, _1_nrm.cfe, _0_MockSep_0.tib, _1_MockVariableIntBlock_0.frq, _2_MockSep_0.pyl, _2_Lucene40_0.frq, _1.si, _0_nrm.cfe, segments_2, _0_MockSep_0.frq, _0.fdx, _0_MockSep_0.tii, _0_MockSep_0.pyl, _0.fdt]
[junit4:junit4]   2> 9023 T1074 C52 P25242 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 9031 T1074 C52 P25242 oass.SolrIndexSearcher.<init> Opening Searcher@7750cd37 main
[junit4:junit4]   2> 9031 T1074 C52 P25242 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9032 T1083 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7750cd37 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10)}
[junit4:junit4]   2> 9033 T1074 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 396
[junit4:junit4]   2> 9033 T1062 C51 P25237 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 571
[junit4:junit4]   2> 9036 T1075 C52 P25242 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9039 T1075 C52 P25242 oass.SolrIndexSearcher.<init> Opening Searcher@734b3995 main
[junit4:junit4]   2> 9039 T1075 C52 P25242 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9040 T1083 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@734b3995 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10)}
[junit4:junit4]   2> 9041 T1075 C52 P25242 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:25237/solr/collection1/, StdNode: http://127.0.0.1:25245/solr/collection1/]
[junit4:junit4]   2> 9043 T1063 C51 P25237 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9043 T1086 C53 P25245 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9044 T1063 C51 P25237 oass.SolrIndexSearcher.<init> Opening Searcher@294e7f58 main
[junit4:junit4]   2> 9044 T1086 C53 P25245 oass.SolrIndexSearcher.<init> Opening Searcher@24bec405 main
[junit4:junit4]   2> 9045 T1063 C51 P25237 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9045 T1086 C53 P25245 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9045 T1095 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:1642)
[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:1519)
[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> 9046 T1071 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@294e7f58 main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C8)}
[junit4:junit4]   2> 9046 T1095 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@24bec405 main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 9047 T1063 C51 P25237 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> 9047 T1086 C53 P25245 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> 9047 T1075 C52 P25242 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 11
[junit4:junit4]   2> 9050 T1087 C53 P25245 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9051 T1087 C53 P25245 oass.SolrIndexSearcher.<init> Opening Searcher@7b9f33bb main
[junit4:junit4]   2> 9052 T1087 C53 P25245 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9053 T1095 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7b9f33bb main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 9053 T1087 C53 P25245 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:25237/solr/collection1/, StdNode: http://127.0.0.1:25242/solr/collection1/]
[junit4:junit4]   2> 9056 T1063 C51 P25237 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9056 T1074 C52 P25242 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 9057 T1063 C51 P25237 oass.SolrIndexSearcher.<init> Opening Searcher@697a899e main
[junit4:junit4]   2> 9057 T1074 C52 P25242 oass.SolrIndexSearcher.<init> Opening Searcher@77d3462 main
[junit4:junit4]   2> 9057 T1063 C51 P25237 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9057 T1074 C52 P25242 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 9058 T1083 oasc.JmxMonitoredMap.put WARNING Failed to register info bean: searcher javax.management.InstanceAlreadyExistsException: solr/collection1:type=searcher,id=org.apache.solr.search.SolrIndexSearcher
[junit4:junit4]   2> 	at com.sun.jmx.mbeanserver.Repository.addMBean(Repository.java:437)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerWithRepository(DefaultMBeanServerInterceptor.java:1898)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerDynamicMBean(DefaultMBeanServerInterceptor.java:966)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerObject(DefaultMBeanServerInterceptor.java:900)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerMBean(DefaultMBeanServerInterceptor.java:324)
[junit4:junit4]   2> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.registerMBean(JmxMBeanServer.java:512)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.put(JmxMonitoredMap.java:140)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.put(JmxMonitoredMap.java:51)
[junit4:junit4]   2> 	at org.apache.solr.search.SolrIndexSearcher.register(SolrIndexSearcher.java:242)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore.registerSearcher(SolrCore.java:1642)
[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:1519)
[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> 9059 T1071 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@697a899e main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C8)}
[junit4:junit4]   2> 9059 T1083 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@77d3462 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10)}
[junit4:junit4]   2> 9059 T1063 C51 P25237 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> 9060 T1074 C52 P25242 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> 9060 T1087 C53 P25245 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 10
[junit4:junit4]   2> 9065 T1048 C50 P25231 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> 9068 T1036 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 9080 T1036 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:25228/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7faab07
[junit4:junit4]   2> 9080 T1102 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:25228
[junit4:junit4]   2> 9080 T1036 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 9082 T1102 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:25228, initiating session
[junit4:junit4]   2> 9082 T1038 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:25255
[junit4:junit4]   2> 9082 T1038 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:25255
[junit4:junit4]   2> 9085 T1040 oazs.NIOServerCnxn.finishSessionInit Established session 0x139bf94fbc50006 with negotiated timeout 10000 for client /140.211.11.196:25255
[junit4:junit4]   2> 9085 T1102 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:25228, sessionid = 0x139bf94fbc50006, negotiated timeout = 10000
[junit4:junit4]   2> 9086 T1103 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7faab07 name:ZooKeeperConnection Watcher:127.0.0.1:25228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 9086 T1036 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 9087 T1036 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 9098 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347538984769&shard.url=127.0.0.1:25237/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9098 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347538984769&shard.url=127.0.0.1:25245/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 9098 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347538984769&shard.url=127.0.0.1:25242/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 9103 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984769&q=*:*&ids=126,128&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9103 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984769&q=*:*&ids=114,6,101&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9103 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984769&q=*:*&ids=135,132,148,123,11&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9105 T1076 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&shards=shard3,shard4,shard5&q=*:*&collection=collection1&wt=javabin&version=2} hits=67 status=0 QTime=11 
[junit4:junit4]   2> 9109 T1048 C50 P25231 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> 9116 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984788&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9116 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984788&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9117 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984788&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9120 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984788&q=*:*&ids=127,121&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9120 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984788&q=*:*&ids=143,115,15,140,130&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9121 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984788&q=*:*&ids=108,110,16&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9122 T1075 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=9 
[junit4:junit4]   2> 9126 T1048 C50 P25231 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> 9133 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984805&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 9133 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984805&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9134 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984805&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9137 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984805&q=*:*&ids=125&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9138 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984805&q=*:*&ids=138,108,8&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9138 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984805&q=*:*&ids=127,128,149,136,137,106&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9139 T1075 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=9 
[junit4:junit4]   2> 9143 T1048 C50 P25231 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_f1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 9150 T1078 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347538984821&shard.url=127.0.0.1:25242/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 9150 T1089 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347538984821&shard.url=127.0.0.1:25245/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 9150 T1066 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347538984821&shard.url=127.0.0.1:25237/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9154 T1066 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984821&q=*:*&ids=13,118&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9154 T1089 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984821&q=*:*&ids=145,131&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9155 T1078 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984821&q=*:*&ids=3,143,117,133,4,140&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9156 T1065 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&shards=shard3,shard4,shard5&q=*:*&collection=collection1&wt=javabin&version=2} hits=67 status=0 QTime=10 
[junit4:junit4]   2> 9160 T1048 C50 P25231 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> 9167 T1077 C52 P25242 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:25242/solr/collection1/&NOW=1347538984839&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 9167 T1064 C51 P25237 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:25237/solr/collection1/&NOW=1347538984839&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9167 T1088 C53 P25245 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:25245/solr/collection1/&NOW=1347538984839&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9170 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984839&q=*:*&ids=4,120,102&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9171 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984839&q=*:*&ids=126,128,147&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9171 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984839&q=*:*&ids=10,110,108,11&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9172 T1075 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 9175 T1048 C50 P25231 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tf1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 9180 T1078 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984853&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9180 T1066 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984853&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 9181 T1089 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984853&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9184 T1066 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984853&q=*:*&ids=132,1&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9185 T1089 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984853&q=*:*&ids=127,149,106,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9184 T1078 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984853&q=*:*&ids=115,9,130,101&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9187 T1065 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=9 
[junit4:junit4]   2> 9193 T1048 C50 P25231 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> 9199 T1090 C53 P25245 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:25245/solr/collection1/&NOW=1347538984870&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9199 T1079 C52 P25242 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:25242/solr/collection1/&NOW=1347538984870&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9200 T1067 C51 P25237 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:25237/solr/collection1/&NOW=1347538984870&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9203 T1090 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984870&q=*:*&ids=147,139,5,149&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9203 T1067 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984870&q=*:*&ids=1,16,8&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9204 T1079 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984870&q=*:*&ids=134,122,102&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9206 T1087 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=11 
[junit4:junit4]   2> 9210 T1048 C50 P25231 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_d1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 9216 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984888&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9216 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984888&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9217 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984888&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9220 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984888&q=*:*&ids=126,112,17&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9221 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984888&q=*:*&ids=117,7,111,12,141&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9220 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984888&q=*:*&ids=135,138&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9222 T1076 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&shards=shard3,shard4,shard5&q=*:*&collection=collection1&wt=javabin&version=2} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 9226 T1048 C50 P25231 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_td1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 9230 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984903&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9231 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984903&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 9231 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984903&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9234 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984903&q=*:*&ids=149,104,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9234 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984903&q=*:*&ids=138,124,16&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9234 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984903&q=*:*&ids=143,107,130,141&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9236 T1075 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 9239 T1048 C50 P25231 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> 9244 T1088 C53 P25245 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:25245/solr/collection1/&NOW=1347538984917&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9244 T1077 C52 P25242 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:25242/solr/collection1/&NOW=1347538984917&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9244 T1064 C51 P25237 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:25237/solr/collection1/&NOW=1347538984917&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 9248 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984917&q=*:*&ids=114,133,4,105,120&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9249 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984917&q=*:*&ids=116,126,112,137,17&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9250 T1075 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 9254 T1048 C50 P25231 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> 9259 T1077 C52 P25242 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:25242/solr/collection1/&NOW=1347538984932&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9259 T1088 C53 P25245 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:25245/solr/collection1/&NOW=1347538984932&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9260 T1064 C51 P25237 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:25237/solr/collection1/&NOW=1347538984932&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9263 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984932&q=*:*&ids=138,123&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9263 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984932&q=*:*&ids=112,136,5,142&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9263 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984932&q=*:*&ids=115,4,102,140&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9264 T1075 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=7 
[junit4:junit4]   2> 9267 T1048 C50 P25231 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> 9273 T1066 C51 P25237 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:25237/solr/collection1/&NOW=1347538984945&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 9273 T1089 C53 P25245 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:25245/solr/collection1/&NOW=1347538984945&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9274 T1078 C52 P25242 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:25242/solr/collection1/&NOW=1347538984945&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 9277 T1066 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984945&q=*:*&ids=110,100&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9277 T1089 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984945&q=*:*&ids=145,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9278 T1078 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984945&q=*:*&ids=3,146,7,107,130,101&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9279 T1065 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=9 
[junit4:junit4]   2> 9283 T1048 C50 P25231 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> 9289 T1066 C51 P25237 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:25237/solr/collection1/&NOW=1347538984961&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9289 T1089 C53 P25245 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:25245/solr/collection1/&NOW=1347538984961&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9290 T1078 C52 P25242 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:25242/solr/collection1/&NOW=1347538984961&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 9293 T1066 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984961&q=*:*&ids=124,14&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9293 T1078 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984961&q=*:*&ids=125,6,9,101&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9294 T1089 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984961&q=*:*&ids=145,121,147,112&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9295 T1062 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=9 
[junit4:junit4]   2> 9298 T1048 C50 P25231 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> 9302 T1088 C53 P25245 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:25245/solr/collection1/&NOW=1347538984976&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9303 T1064 C51 P25237 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:25237/solr/collection1/&NOW=1347538984976&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 9303 T1077 C52 P25242 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:25242/solr/collection1/&NOW=1347538984976&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9307 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984976&q=*:*&ids=126,139,106&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9307 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984976&q=*:*&ids=134,3,122,111&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9308 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984976&q=*:*&ids=1,108,118&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9309 T1076 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 9312 T1048 C50 P25231 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_dt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 9317 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984990&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9317 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984990&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9318 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984990&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9321 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538984990&q=*:*&ids=143,113,141&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9321 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538984990&q=*:*&ids=10,138,110,123&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9322 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538984990&q=*:*&ids=127,121,106&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9323 T1075 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 9326 T1048 C50 P25231 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> 9332 T1078 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538985004&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9332 T1089 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538985004&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9332 T1066 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538985004&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 9336 T1066 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538985004&q=*:*&ids=8,16,14,11&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9336 T1078 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538985004&q=*:*&ids=125,2,115,122,107,4&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9337 T1065 C51 P25237 REQ [collection1] webapp=/solr path=/select params={collection=collection1&sort=n_dt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 9341 T1048 C50 P25231 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> 9346 T1066 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538985019&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 9347 T1078 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538985019&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9348 T1089 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538985019&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 9351 T1066 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538985019&q=*:*&ids=110,16&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9352 T1078 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538985019&q=*:*&ids=134,144,146,15,130&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9352 T1089 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538985019&q=*:*&ids=126,128,5&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9352 T1065 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&shards=shard3,shard4,shard5&q=*:*&collection=collection1&wt=javabin&version=2} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 9356 T1048 C50 P25231 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> 9363 T1064 C51 P25237 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:25237/solr/collection1/&NOW=1347538985035&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 9363 T1088 C53 P25245 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:25245/solr/collection1/&NOW=1347538985035&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9364 T1077 C52 P25242 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:25242/solr/collection1/&NOW=1347538985035&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 9367 T1064 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538985035&q=*:*&ids=1,10,123&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9367 T1088 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538985035&q=*:*&ids=147,136,104&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9368 T1077 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538985035&q=*:*&ids=143,120,129,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9369 T1075 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=9 
[junit4:junit4]   2> 9372 T1048 C50 P25231 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=a_si+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 9377 T1090 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347538985049&shard.url=127.0.0.1:25245/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 9377 T1079 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347538985049&shard.url=127.0.0.1:25242/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9378 T1067 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347538985049&shard.url=127.0.0.1:25237/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 9381 T1090 C53 P25245 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25245/solr/collection1/&NOW=1347538985049&q=*:*&ids=5&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 9382 T1067 C51 P25237 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538985049&q=*:*&ids=10,1,8,13&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9382 T1079 C52 P25242 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538985049&q=*:*&ids=3,2,7,9,12&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 9383 T1091 C53 P25245 REQ [collection1] webapp=/solr path=/select params={collection=collection1&sort=a_si+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=9 
[junit4:junit4]   2> 9401 T1048 C50 P25231 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=a_si+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 9407 T1066 C51 P25237 REQ [collection1] webapp=/solr path=/select params={sort=a_si+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25237/solr/collection1/&NOW=1347538985079&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 9407 T1078 C52 P25242 REQ [collection1] webapp=/solr path=/select params={sort=a_si+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25242/solr/collection1/&NOW=1347538985079&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 9408 T1089 C53 P25245 REQ [collection1] webapp=/solr path=/select params={sort=a_si+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:25245/solr/collection1/&

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

uleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]    > Throwable #2: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1058, name=Overseer-88312314310492162-127.0.0.1:25231_solr-n_0000000000, state=RUNNABLE, group=Overseer collection creation process.]
[junit4:junit4]    > Caused by: org.apache.solr.common.SolrException: java.lang.InterruptedException
[junit4:junit4]    > 	at org.apache.solr.handler.component.HttpShardHandler.take(HttpShardHandler.java:225)
[junit4:junit4]    > 	at org.apache.solr.handler.component.HttpShardHandler.takeCompletedOrError(HttpShardHandler.java:205)
[junit4:junit4]    > 	at org.apache.solr.cloud.OverseerCollectionProcessor.createCollection(OverseerCollectionProcessor.java:218)
[junit4:junit4]    > 	at org.apache.solr.cloud.OverseerCollectionProcessor.processMessage(OverseerCollectionProcessor.java:136)
[junit4:junit4]    > 	at org.apache.solr.cloud.OverseerCollectionProcessor.run(OverseerCollectionProcessor.java:90)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]    > Caused by: java.lang.InterruptedException
[junit4:junit4]    > 	at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1219)
[junit4:junit4]    > 	at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:340)
[junit4:junit4]    > 	at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:439)
[junit4:junit4]    > 	at java.util.concurrent.ExecutorCompletionService.take(ExecutorCompletionService.java:193)
[junit4:junit4]    > 	at org.apache.solr.handler.component.HttpShardHandler.take(HttpShardHandler.java:212)
[junit4:junit4]    > 	... 5 more
[junit4:junit4]   2> 207702 T1036 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {n_f1=Lucene40(minBlockSize=28 maxBlockSize=68), foo_b=PostingsFormat(name=TestBloomFilteredLucene40Postings), cat=PostingsFormat(name=MockSep), foo_d=MockVariableIntBlock(baseBlockSize=10), foo_f=PostingsFormat(name=TestBloomFilteredLucene40Postings), n_tl1=Lucene40(minBlockSize=28 maxBlockSize=68), n_d1=PostingsFormat(name=MockSep), rnd_b=MockVariableIntBlock(baseBlockSize=10), intDefault=PostingsFormat(name=MockSep), n_td1=Lucene40(minBlockSize=28 maxBlockSize=68), timestamp=PostingsFormat(name=MockSep), id=MockVariableIntBlock(baseBlockSize=10), range_facet_sl=PostingsFormat(name=TestBloomFilteredLucene40Postings), range_facet_si=Lucene40(minBlockSize=28 maxBlockSize=68), oddField_s=PostingsFormat(name=TestBloomFilteredLucene40Postings), sequence_i=PostingsFormat(name=TestBloomFilteredLucene40Postings), name=MockVariableIntBlock(baseBlockSize=10), foo_i=Lucene40(minBlockSize=28 maxBlockSize=68), regex_dup_B_s=PostingsFormat(name=MockSep), multiDefault=Lucene40(minBlockSize=28 maxBlockSize=68), n_tf1=PostingsFormat(name=MockSep), n_dt1=Lucene40(minBlockSize=28 maxBlockSize=68), genre_s=PostingsFormat(name=TestBloomFilteredLucene40Postings), author_t=Lucene40(minBlockSize=28 maxBlockSize=68), n_ti1=MockVariableIntBlock(baseBlockSize=10), range_facet_l=PostingsFormat(name=MockSep), text=PostingsFormat(name=TestBloomFilteredLucene40Postings), _version_=PostingsFormat(name=MockSep), val_i=MockVariableIntBlock(baseBlockSize=10), SubjectTerms_mfacet=PostingsFormat(name=MockSep), series_t=Lucene40(minBlockSize=28 maxBlockSize=68), a_t=PostingsFormat(name=MockSep), n_tdt1=PostingsFormat(name=MockSep), regex_dup_A_s=PostingsFormat(name=TestBloomFilteredLucene40Postings), price=PostingsFormat(name=TestBloomFilteredLucene40Postings), other_tl1=PostingsFormat(name=MockSep), n_l1=PostingsFormat(name=MockSep), a_si=Lucene40(minBlockSize=28 maxBlockSize=68), inStock=PostingsFormat(name=TestBloomFilteredLucene40Postings)}, sim=DefaultSimilarity, locale=fr_FR, timezone=Asia/Jerusalem
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Oracle Corporation 1.7.0_06 (64-bit)/cpus=16,threads=1,free=141051408,total=274726912
[junit4:junit4]   2> NOTE: All tests run in this JVM: [BadComponentTest, LeaderElectionIntegrationTest, DistanceFunctionTest, TestFaceting, TestLMDirichletSimilarityFactory, AlternateDirectoryTest, DateFieldTest, TestLMJelinekMercerSimilarityFactory, TestSearchPerf, CacheHeaderTest, AnalysisAfterCoreReloadTest, ZkControllerTest, TestStressReorder, SolrRequestParserTest, SpatialFilterTest, SolrCmdDistributorTest, UniqFieldsUpdateProcessorFactoryTest, TestIndexSearcher, TestRandomFaceting, ZkCLITest, TermsComponentTest, BinaryUpdateRequestHandlerTest, LoggingHandlerTest, TestQuerySenderNoQuery, SimpleFacetsTest, SampleTest, SystemInfoHandlerTest, TestCollationField, TestFastOutputStream, PluginInfoTest, DistributedSpellCheckComponentTest, TestElisionMultitermQuery, SimplePostToolTest, UpdateRequestProcessorFactoryTest, FieldAnalysisRequestHandlerTest, SuggesterFSTTest, TestBadConfig, TestSystemIdResolver, SpellCheckComponentTest, DefaultValueUpdateProcessorTest, IndexBasedSpellCheckerTest, SolrPluginUtilsTest, DirectUpdateHandlerOptimizeTest, TestSolrJ, TestJoin, SortByFunctionTest, MBeansHandlerTest, JSONWriterTest, TestLFUCache, ResourceLoaderTest, TestXIncludeConfig, TestSuggestSpellingConverter, TermVectorComponentDistributedTest, TestNumberUtils, TestBinaryResponseWriter, BasicDistributedZkTest]
[junit4:junit4] Completed on J0 in 208.03s, 1 test, 1 error <<< FAILURES!

[...truncated 361 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:1133: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-java7/lucene/common-build.xml:810: There were test failures: 228 suites, 922 tests, 1 error, 6 ignored (1 assumption)

Total time: 30 minutes 25 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