lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_07) - Build # 711 - Failure!
Date Mon, 10 Sep 2012 20:12:37 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows/711/
Java: 32bit/jdk1.7.0_07 -client -XX:+UseConcMarkSweepGC

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

Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.BasicDistributedZkTest:     1) Thread[id=1956, name=SessionTracker, state=TIMED_WAITING, group=TGRP-BasicDistributedZkTest]         at java.lang.Object.wait(Native Method)         at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:145)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.BasicDistributedZkTest: 
   1) Thread[id=1956, name=SessionTracker, state=TIMED_WAITING, group=TGRP-BasicDistributedZkTest]
        at java.lang.Object.wait(Native Method)
        at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:145)
	at __randomizedtesting.SeedInfo.seed([44E11591A5BB0316]:0)




Build Log:
[...truncated 7849 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 3 T1953 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-BasicDistributedZkTest-1347307285399
[junit4:junit4]   2> 7 T1953 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 11 T1954 oazs.ZooKeeperServer.setTickTime tickTime set to 3000
[junit4:junit4]   2> 12 T1954 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 14 T1954 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 116 T1953 oasc.ZkTestServer.run start zk server on port:58732
[junit4:junit4]   2> 116 T1953 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58732 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@13b70cd
[junit4:junit4]   2> 118 T1959 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58732
[junit4:junit4]   2> 118 T1953 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 119 T1959 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58732, initiating session
[junit4:junit4]   2> 120 T1955 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58735
[junit4:junit4]   2> 120 T1955 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58735
[junit4:junit4]   2> 121 T1957 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 125 T1957 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b1c5aba20000 with negotiated timeout 10000 for client /127.0.0.1:58735
[junit4:junit4]   2> 125 T1959 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58732, sessionid = 0x139b1c5aba20000, negotiated timeout = 10000
[junit4:junit4]   2> 125 T1960 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13b70cd name:ZooKeeperConnection Watcher:127.0.0.1:58732 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 126 T1953 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 127 T1953 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 131 T1958 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139b1c5aba20000
[junit4:junit4]   2> 132 T1955 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139b1c5aba20000, likely client has closed socket
[junit4:junit4]   2> 132 T1960 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 132 T1953 oaz.ZooKeeper.close Session: 0x139b1c5aba20000 closed
[junit4:junit4]   2> 134 T1955 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58735 which had sessionid 0x139b1c5aba20000
[junit4:junit4]   2> 134 T1953 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58732/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1babd9d
[junit4:junit4]   2> 137 T1953 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 137 T1961 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58732
[junit4:junit4]   2> 138 T1961 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58732, initiating session
[junit4:junit4]   2> 139 T1955 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58738
[junit4:junit4]   2> 139 T1955 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58738
[junit4:junit4]   2> 141 T1957 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b1c5aba20001 with negotiated timeout 10000 for client /127.0.0.1:58738
[junit4:junit4]   2> 141 T1961 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58732, sessionid = 0x139b1c5aba20001, negotiated timeout = 10000
[junit4:junit4]   2> 141 T1962 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1babd9d name:ZooKeeperConnection Watcher:127.0.0.1:58732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 141 T1953 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 141 T1953 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 147 T1953 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 151 T1953 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 154 T1953 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 158 T1953 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 159 T1953 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 167 T1953 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 169 T1953 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 173 T1953 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 173 T1953 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 178 T1953 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 178 T1953 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 183 T1953 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 183 T1953 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 188 T1953 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 188 T1953 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 193 T1953 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 193 T1953 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 198 T1953 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 198 T1953 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 202 T1953 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 202 T1953 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 213 T1953 oasc.AbstractZkTestCase.putConfig put C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 214 T1953 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 217 T1958 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139b1c5aba20001
[junit4:junit4]   2> 219 T1953 oaz.ZooKeeper.close Session: 0x139b1c5aba20001 closed
[junit4:junit4]   2> 219 T1955 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x139b1c5aba20001, likely client has closed socket
[junit4:junit4]   2> 219 T1955 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58738 which had sessionid 0x139b1c5aba20001
[junit4:junit4]   2> 220 T1962 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 459 T1953 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 464 T1953 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:58739
[junit4:junit4]   2> 465 T1953 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 465 T1953 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1
[junit4:junit4]   2> 465 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\'
[junit4:junit4]   2> 499 T1953 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 500 T1953 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 500 T1953 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1
[junit4:junit4]   2> 500 T1953 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\solr.xml
[junit4:junit4]   2> 501 T1953 oasc.CoreContainer.<init> New CoreContainer 3810357
[junit4:junit4]   2> 501 T1953 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\'
[junit4:junit4]   2> 501 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\'
[junit4:junit4]   2> 531 T1953 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 558 T1953 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:58732/solr
[junit4:junit4]   2> 559 T1953 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58732/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@af28b2
[junit4:junit4]   2> 562 T1972 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58732
[junit4:junit4]   2> 562 T1953 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 564 T1972 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58732, initiating session
[junit4:junit4]   2> 564 T1955 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58742
[junit4:junit4]   2> 565 T1955 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58742
[junit4:junit4]   2> 572 T1957 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b1c5aba20002 with negotiated timeout 8000 for client /127.0.0.1:58742
[junit4:junit4]   2> 572 T1972 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58732, sessionid = 0x139b1c5aba20002, negotiated timeout = 8000
[junit4:junit4]   2> 572 T1973 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@af28b2 name:ZooKeeperConnection Watcher:127.0.0.1:58732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 572 T1953 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 575 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 577 T1953 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 581 T1953 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58739_solr
[junit4:junit4]   2> 582 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:58739_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:58739_solr
[junit4:junit4]   2> 583 T1953 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58739_solr
[junit4:junit4]   2> 587 T1953 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 587 T1953 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 588 T1953 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 588 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 588 T1953 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 589 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 589 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 589 T1953 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 590 T1953 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 590 T1953 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 599 T1953 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 608 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 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> 610 T1953 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 613 T1953 oasc.Overseer.start Overseer (id=88297130256629762-127.0.0.1:58739_solr-n_0000000000) starting
[junit4:junit4]   2> 614 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 616 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 618 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 620 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 621 T1975 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 622 T1953 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 626 T1953 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 630 T1974 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 639 T1953 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1
[junit4:junit4]   2> 639 T1953 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 641 T1953 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 641 T1953 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 643 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\'
[junit4:junit4]   2> 646 T1953 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/control1/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 647 T1953 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/control1/collection1/lib/README' to classloader
[junit4:junit4]   2> 708 T1953 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 800 T1953 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 810 T1953 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 815 T1953 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1663 T1953 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1674 T1953 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1678 T1953 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1695 T1953 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1704 T1953 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1711 T1953 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1712 T1953 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1715 T1953 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\
[junit4:junit4]   2> 1716 T1953 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a75
[junit4:junit4]   2> 1716 T1953 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index/
[junit4:junit4]   2> 1717 T1953 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1717 T1953 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index forceNew:false
[junit4:junit4]   2> 1726 T1953 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f214c5; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1727 T1953 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1728 T1953 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1730 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1730 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1730 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1731 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1732 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1732 T1953 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1733 T1953 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1733 T1953 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1734 T1953 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1734 T1953 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1735 T1953 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1735 T1953 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1736 T1953 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1736 T1953 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1737 T1953 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1737 T1953 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1739 T1953 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1739 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1739 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1740 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1740 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1741 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1741 T1953 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1742 T1953 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1742 T1953 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1746 T1953 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1751 T1953 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1752 T1953 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> 1758 T1953 oass.SolrIndexSearcher.<init> Opening Searcher@1ef62c2 main
[junit4:junit4]   2> 1759 T1953 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1760 T1953 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1760 T1953 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1764 T1953 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1780 T1976 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ef62c2 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1783 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 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> 1789 T1953 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1790 T1953 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:58739/solr shardId:control_shard
[junit4:junit4]   2> 1790 T1953 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1800 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 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> 1802 T1953 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 1803 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1808 T1953 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58739/solr/collection1/
[junit4:junit4]   2> 1808 T1953 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1816 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 type:create cxid:0x6f zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2243 T1974 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2245 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20002 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> 2266 T1973 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2310 T1953 oasc.ZkController.register We are http://127.0.0.1:58739/solr/collection1/ and leader is http://127.0.0.1:58739/solr/collection1/
[junit4:junit4]   2> 2310 T1953 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58739/solr
[junit4:junit4]   2> 2311 T1953 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2314 T1953 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2316 T1953 oass.SolrDispatchFilter.init user.dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 2317 T1953 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2317 T1953 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2587 T1953 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2591 T1953 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:58743
[junit4:junit4]   2> 2593 T1953 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2594 T1953 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2
[junit4:junit4]   2> 2594 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\'
[junit4:junit4]   2> 2630 T1953 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2631 T1953 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2631 T1953 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2
[junit4:junit4]   2> 2632 T1953 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\solr.xml
[junit4:junit4]   2> 2632 T1953 oasc.CoreContainer.<init> New CoreContainer 14689975
[junit4:junit4]   2> 2632 T1953 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\'
[junit4:junit4]   2> 2632 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\'
[junit4:junit4]   2> 2662 T1953 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2690 T1953 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:58732/solr
[junit4:junit4]   2> 2690 T1953 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58732/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1f67586
[junit4:junit4]   2> 2692 T1986 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58732
[junit4:junit4]   2> 2692 T1953 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2694 T1986 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58732, initiating session
[junit4:junit4]   2> 2694 T1955 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58746
[junit4:junit4]   2> 2695 T1955 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58746
[junit4:junit4]   2> 2708 T1957 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b1c5aba20003 with negotiated timeout 8000 for client /127.0.0.1:58746
[junit4:junit4]   2> 2708 T1986 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58732, sessionid = 0x139b1c5aba20003, negotiated timeout = 8000
[junit4:junit4]   2> 2708 T1987 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f67586 name:ZooKeeperConnection Watcher:127.0.0.1:58732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2709 T1953 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2710 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2711 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2713 T1953 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58743_solr
[junit4:junit4]   2> 2714 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:58743_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:58743_solr
[junit4:junit4]   2> 2715 T1953 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58743_solr
[junit4:junit4]   2> 2718 T1973 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2720 T1953 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2720 T1953 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2720 T1953 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2721 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2721 T1953 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2721 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2721 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2722 T1953 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2722 T1953 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2724 T1953 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2737 T1953 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2744 T1953 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1
[junit4:junit4]   2> 2745 T1953 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2746 T1953 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2746 T1953 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2749 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\'
[junit4:junit4]   2> 2751 T1953 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty2/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2752 T1953 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty2/collection1/lib/README' to classloader
[junit4:junit4]   2> 2804 T1953 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2813 T1974 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2832 T1973 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2833 T1987 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2906 T1953 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2907 T1953 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2921 T1953 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3688 T1953 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3698 T1953 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3703 T1953 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3736 T1953 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3741 T1953 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3747 T1953 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3750 T1953 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3751 T1953 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\
[junit4:junit4]   2> 3751 T1953 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a75
[junit4:junit4]   2> 3752 T1953 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index/
[junit4:junit4]   2> 3759 T1953 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3760 T1953 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index forceNew:false
[junit4:junit4]   2> 3769 T1953 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d89c7a; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3770 T1953 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3771 T1953 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3773 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3774 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3774 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3775 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3775 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3776 T1953 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3776 T1953 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3777 T1953 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3777 T1953 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3778 T1953 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3778 T1953 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3779 T1953 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3779 T1953 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3780 T1953 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3780 T1953 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3781 T1953 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3781 T1953 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3782 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3784 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3785 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3785 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3786 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3787 T1953 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3787 T1953 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3788 T1953 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3792 T1953 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3797 T1953 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3798 T1953 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> 3803 T1953 oass.SolrIndexSearcher.<init> Opening Searcher@1012ead main
[junit4:junit4]   2> 3804 T1953 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3804 T1953 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3805 T1953 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3809 T1953 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3825 T1988 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1012ead main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3833 T1953 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3833 T1953 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:58743/solr shardId:shard3
[junit4:junit4]   2> 3834 T1953 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
[junit4:junit4]   2> 3845 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20003 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> 3847 T1953 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 3848 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20003 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3851 T1953 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58743/solr/collection1/
[junit4:junit4]   2> 3851 T1953 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
[junit4:junit4]   2> 3859 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20003 type:create cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3908 T1974 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3924 T1973 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3924 T1987 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3971 T1953 oasc.ZkController.register We are http://127.0.0.1:58743/solr/collection1/ and leader is http://127.0.0.1:58743/solr/collection1/
[junit4:junit4]   2> 3971 T1953 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58743/solr
[junit4:junit4]   2> 3972 T1953 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3975 T1953 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3976 T1953 oass.SolrDispatchFilter.init user.dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 3976 T1953 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3977 T1953 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4259 T1953 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4263 T1953 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:58747
[junit4:junit4]   2> 4265 T1953 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4266 T1953 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3
[junit4:junit4]   2> 4266 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\'
[junit4:junit4]   2> 4314 T1953 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4315 T1953 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4315 T1953 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3
[junit4:junit4]   2> 4315 T1953 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\solr.xml
[junit4:junit4]   2> 4316 T1953 oasc.CoreContainer.<init> New CoreContainer 29870908
[junit4:junit4]   2> 4317 T1953 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\'
[junit4:junit4]   2> 4318 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\'
[junit4:junit4]   2> 4345 T1953 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4370 T1953 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:58732/solr
[junit4:junit4]   2> 4370 T1953 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58732/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@2aec84
[junit4:junit4]   2> 4372 T1998 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58732
[junit4:junit4]   2> 4372 T1953 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4375 T1998 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58732, initiating session
[junit4:junit4]   2> 4375 T1955 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58750
[junit4:junit4]   2> 4375 T1955 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58750
[junit4:junit4]   2> 4383 T1957 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b1c5aba20004 with negotiated timeout 8000 for client /127.0.0.1:58750
[junit4:junit4]   2> 4383 T1998 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58732, sessionid = 0x139b1c5aba20004, negotiated timeout = 8000
[junit4:junit4]   2> 4383 T1999 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2aec84 name:ZooKeeperConnection Watcher:127.0.0.1:58732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4384 T1953 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4385 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4387 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4389 T1953 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58747_solr
[junit4:junit4]   2> 4389 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:58747_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:58747_solr
[junit4:junit4]   2> 4391 T1953 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58747_solr
[junit4:junit4]   2> 4394 T1973 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4394 T1987 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4395 T1953 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4395 T1953 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4395 T1953 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4397 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4397 T1953 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4397 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4397 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4397 T1953 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4397 T1953 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4398 T1953 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4412 T1953 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4419 T1953 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1
[junit4:junit4]   2> 4419 T1953 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4420 T1953 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4420 T1953 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4422 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\'
[junit4:junit4]   2> 4424 T1953 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty3/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4426 T1953 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty3/collection1/lib/README' to classloader
[junit4:junit4]   2> 4469 T1974 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4479 T1999 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4480 T1973 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4481 T1987 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4484 T1953 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4577 T1953 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4578 T1953 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4584 T1953 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5369 T1953 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5380 T1953 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5386 T1953 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5403 T1953 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5408 T1953 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5415 T1953 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5417 T1953 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5424 T1953 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\
[junit4:junit4]   2> 5424 T1953 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a75
[junit4:junit4]   2> 5425 T1953 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index/
[junit4:junit4]   2> 5425 T1953 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5426 T1953 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index forceNew:false
[junit4:junit4]   2> 5436 T1953 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@13daabc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5437 T1953 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5438 T1953 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5440 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5441 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5441 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5442 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5442 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5443 T1953 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5443 T1953 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5444 T1953 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5444 T1953 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5445 T1953 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5445 T1953 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5446 T1953 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5446 T1953 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5447 T1953 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5447 T1953 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5448 T1953 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5448 T1953 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5449 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5450 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5450 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5451 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5451 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5452 T1953 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5452 T1953 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5453 T1953 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5457 T1953 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5463 T1953 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5465 T1953 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> 5469 T1953 oass.SolrIndexSearcher.<init> Opening Searcher@85179 main
[junit4:junit4]   2> 5471 T1953 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5471 T1953 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5472 T1953 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5475 T1953 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5493 T2000 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@85179 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5499 T1953 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5499 T1953 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:58747/solr shardId:shard4
[junit4:junit4]   2> 5500 T1953 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard4/election
[junit4:junit4]   2> 5511 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20004 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> 5512 T1953 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 5513 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20004 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5516 T1953 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58747/solr/collection1/
[junit4:junit4]   2> 5516 T1953 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard4
[junit4:junit4]   2> 5521 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20004 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5555 T1974 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5573 T1973 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5574 T1999 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5574 T1987 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5578 T1953 oasc.ZkController.register We are http://127.0.0.1:58747/solr/collection1/ and leader is http://127.0.0.1:58747/solr/collection1/
[junit4:junit4]   2> 5578 T1953 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58747/solr
[junit4:junit4]   2> 5578 T1953 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 5583 T1953 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5585 T1953 oass.SolrDispatchFilter.init user.dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 5586 T1953 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5587 T1953 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5868 T1953 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5873 T1953 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:58751
[junit4:junit4]   2> 5875 T1953 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5875 T1953 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4
[junit4:junit4]   2> 5876 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\'
[junit4:junit4]   2> 5910 T1953 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5911 T1953 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5911 T1953 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4
[junit4:junit4]   2> 5912 T1953 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\solr.xml
[junit4:junit4]   2> 5912 T1953 oasc.CoreContainer.<init> New CoreContainer 14534671
[junit4:junit4]   2> 5913 T1953 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\'
[junit4:junit4]   2> 5913 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\'
[junit4:junit4]   2> 5942 T1953 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5967 T1953 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:58732/solr
[junit4:junit4]   2> 5967 T1953 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58732/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@12119bc
[junit4:junit4]   2> 5969 T2010 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58732
[junit4:junit4]   2> 5969 T1953 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5973 T2010 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58732, initiating session
[junit4:junit4]   2> 5974 T1955 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58754
[junit4:junit4]   2> 5974 T1955 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58754
[junit4:junit4]   2> 5977 T1957 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b1c5aba20005 with negotiated timeout 8000 for client /127.0.0.1:58754
[junit4:junit4]   2> 5977 T2010 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58732, sessionid = 0x139b1c5aba20005, negotiated timeout = 8000
[junit4:junit4]   2> 5977 T2011 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12119bc name:ZooKeeperConnection Watcher:127.0.0.1:58732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5978 T1953 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5979 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5981 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5982 T1953 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58751_solr
[junit4:junit4]   2> 5983 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:58751_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:58751_solr
[junit4:junit4]   2> 5984 T1953 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58751_solr
[junit4:junit4]   2> 5989 T1999 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5989 T1987 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5989 T1973 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 5991 T1953 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5991 T1953 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5992 T1953 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5992 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5992 T1953 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5994 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5994 T1953 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5995 T1953 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5995 T1953 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5996 T1953 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6009 T1953 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6016 T1953 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1
[junit4:junit4]   2> 6016 T1953 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6018 T1953 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6018 T1953 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6019 T1953 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\'
[junit4:junit4]   2> 6021 T1953 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty4/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6022 T1953 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/jetty4/collection1/lib/README' to classloader
[junit4:junit4]   2> 6101 T1953 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 6135 T1974 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6146 T1999 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6149 T1973 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6149 T1987 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6150 T2011 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6205 T1953 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6207 T1953 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6223 T1953 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7053 T1953 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 7066 T1953 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7071 T1953 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7086 T1953 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7091 T1953 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7096 T1953 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7102 T1953 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7103 T1953 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\
[junit4:junit4]   2> 7103 T1953 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@17a75
[junit4:junit4]   2> 7105 T1953 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index/
[junit4:junit4]   2> 7106 T1953 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 7106 T1953 oasc.CachingDirectoryFactory.get return new directory for C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index forceNew:false
[junit4:junit4]   2> 7115 T1953 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@11f3d09; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7116 T1953 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 7117 T1953 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 7119 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7119 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 7120 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7120 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 7121 T1953 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 7121 T1953 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7122 T1953 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7123 T1953 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7123 T1953 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7124 T1953 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 7125 T1953 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 7125 T1953 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7126 T1953 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 7126 T1953 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 7127 T1953 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 7128 T1953 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7129 T1953 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7129 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7130 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7131 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7131 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7132 T1953 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7133 T1953 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7133 T1953 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 7135 T1953 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 7139 T1953 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7144 T1953 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 7146 T1953 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> 7150 T1953 oass.SolrIndexSearcher.<init> Opening Searcher@1ffadad main
[junit4:junit4]   2> 7152 T1953 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7152 T1953 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7153 T1953 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 7156 T1953 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 7173 T2012 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ffadad main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7179 T1953 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 7179 T1953 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:58751/solr shardId:shard5
[junit4:junit4]   2> 7180 T1953 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard5/election
[junit4:junit4]   2> 7189 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20005 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> 7190 T1953 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process. afterExpiration=false
[junit4:junit4]   2> 7191 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20005 type:create cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7194 T1953 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58751/solr/collection1/
[junit4:junit4]   2> 7194 T1953 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard5
[junit4:junit4]   2> 7198 T1958 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139b1c5aba20005 type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7225 T1974 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7242 T1973 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7242 T1987 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7242 T1999 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7242 T2011 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7255 T1953 oasc.ZkController.register We are http://127.0.0.1:58751/solr/collection1/ and leader is http://127.0.0.1:58751/solr/collection1/
[junit4:junit4]   2> 7255 T1953 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58751/solr
[junit4:junit4]   2> 7256 T1953 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 7259 T1953 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7260 T1953 oass.SolrDispatchFilter.init user.dir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 7260 T1953 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7260 T1953 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C89 name=collection1 org.apache.solr.core.SolrCore@a921ee url=http://127.0.0.1:58739/solr/collection1 node=127.0.0.1:58739_solr
[junit4:junit4]   2> 7280 T1965 C89 P58739 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f214c5; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7281 T1965 C89 P58739 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 7284 T1965 C89 P58739 oass.SolrIndexSearcher.<init> Opening Searcher@b0dc4 realtime
[junit4:junit4]   2> 7284 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412754091718737920)} 0 8
[junit4:junit4]   2> ASYNC  NEW_CORE C90 name=collection1 org.apache.solr.core.SolrCore@17290de url=http://127.0.0.1:58743/solr/collection1 node=127.0.0.1:58743_solr
[junit4:junit4]   2> 7297 T1979 C90 P58743 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d89c7a; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7298 T1979 C90 P58743 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 7300 T1979 C90 P58743 oass.SolrIndexSearcher.<init> Opening Searcher@103da59 realtime
[junit4:junit4]   2> ASYNC  NEW_CORE C91 name=collection1 org.apache.solr.core.SolrCore@1b6b313 url=http://127.0.0.1:58751/solr/collection1 node=127.0.0.1:58751_solr
[junit4:junit4]   2> 7314 T2003 C91 P58751 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@11f3d09; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7315 T2003 C91 P58751 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 7317 T2003 C91 P58751 oass.SolrIndexSearcher.<init> Opening Searcher@1aad11b realtime
[junit4:junit4]   2> 7317 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> ASYNC  NEW_CORE C92 name=collection1 org.apache.solr.core.SolrCore@db9b3a url=http://127.0.0.1:58747/solr/collection1 node=127.0.0.1:58747_solr
[junit4:junit4]   2> 7317 T1991 C92 P58747 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@13daabc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7318 T1991 C92 P58747 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 7320 T1991 C92 P58747 oass.SolrIndexSearcher.<init> Opening Searcher@1cb49d1 realtime
[junit4:junit4]   2> 7320 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 7321 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412754091735515136)} 0 29
[junit4:junit4]   2> 7328 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7328 T1980 C90 P58743 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7328 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412754091769069568)} 0 5
[junit4:junit4]   2> 7335 T1980 C90 P58743 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7335 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7336 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1412754091775361024)} 0 6
[junit4:junit4]   2> 7340 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1412754091783749632)]} 0 2
[junit4:junit4]   2> 7344 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1412754091788992512)]} 0 1
[junit4:junit4]   2> 7347 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1412754091793186816)]} 0 0
[junit4:junit4]   2> 7351 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1412754091796332544)]} 0 1
[junit4:junit4]   2> 7355 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3 (1412754091801575424)]} 0 0
[junit4:junit4]   2> 7362 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7363 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 4
[junit4:junit4]   2> 7365 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4 (1412754091812061184)]} 0 0
[junit4:junit4]   2> 7372 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7372 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 3
[junit4:junit4]   2> 7375 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5 (1412754091822546944)]} 0 0
[junit4:junit4]   2> 7384 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 7384 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 6
[junit4:junit4]   2> 7387 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6 (1412754091835129856)]} 0 0
[junit4:junit4]   2> 7394 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7402 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 11
[junit4:junit4]   2> 7406 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7 (1412754091855052800)]} 0 0
[junit4:junit4]   2> 7412 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7412 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 3
[junit4:junit4]   2> 7418 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8 (1412754091867635712)]} 0 0
[junit4:junit4]   2> 7425 T1980 C90 P58743 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7426 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 4
[junit4:junit4]   2> 7428 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9 (1412754091878121472)]} 0 0
[junit4:junit4]   2> 7435 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7436 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 4
[junit4:junit4]   2> 7444 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10 (1412754091888607232)]} 0 6
[junit4:junit4]   2> 7447 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10 (1412754091898044416)]} 0 0
[junit4:junit4]   2> 7451 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11 (1412754091901190144)]} 0 1
[junit4:junit4]   2> 7458 T1980 C90 P58743 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7459 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 4
[junit4:junit4]   2> 7461 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12 (1412754091912724480)]} 0 0
[junit4:junit4]   2> 7467 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7468 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 4
[junit4:junit4]   2> 7470 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13 (1412754091922161664)]} 0 0
[junit4:junit4]   2> 7475 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13 (1412754091926355968)]} 0 1
[junit4:junit4]   2> 7478 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14 (1412754091930550272)]} 0 0
[junit4:junit4]   2> 7486 T1980 C90 P58743 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7487 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 4
[junit4:junit4]   2> 7489 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15 (1412754091942084608)]} 0 0
[junit4:junit4]   2> 7494 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7495 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 3
[junit4:junit4]   2> 7498 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16 (1412754091951521792)]} 0 0
[junit4:junit4]   2> 7501 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16 (1412754091954667520)]} 0 0
[junit4:junit4]   2> 7507 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17 (1412754091959910400)]} 0 1
[junit4:junit4]   2> 7514 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7515 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 5
[junit4:junit4]   2> 7517 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100 (1412754091971444736)]} 0 0
[junit4:junit4]   2> 7522 T1980 C90 P58743 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7523 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[100]} 0 3
[junit4:junit4]   2> 7526 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101 (1412754091980881920)]} 0 0
[junit4:junit4]   2> 7533 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7534 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[101]} 0 3
[junit4:junit4]   2> 7542 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102 (1412754091991367680)]} 0 6
[junit4:junit4]   2> 7554 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 7555 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[102]} 0 10
[junit4:junit4]   2> 7558 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103 (1412754092013387776)]} 0 1
[junit4:junit4]   2> 7564 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7565 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[103]} 0 3
[junit4:junit4]   2> 7567 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104 (1412754092023873536)]} 0 0
[junit4:junit4]   2> 7570 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[104 (1412754092027019264)]} 0 0
[junit4:junit4]   2> 7573 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105 (1412754092030164992)]} 0 0
[junit4:junit4]   2> 7581 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7581 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[105]} 0 5
[junit4:junit4]   2> 7584 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106 (1412754092041699328)]} 0 0
[junit4:junit4]   2> 7589 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7590 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[106]} 0 3
[junit4:junit4]   2> 7592 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107 (1412754092050087936)]} 0 0
[junit4:junit4]   2> 7597 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7598 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[107]} 0 3
[junit4:junit4]   2> 7600 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108 (1412754092058476544)]} 0 0
[junit4:junit4]   2> 7604 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[108 (1412754092062670848)]} 0 0
[junit4:junit4]   2> 7608 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109 (1412754092066865152)]} 0 0
[junit4:junit4]   2> 7619 T1980 C90 P58743 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 7620 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[109]} 0 9
[junit4:junit4]   2> 7623 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110 (1412754092082593792)]} 0 0
[junit4:junit4]   2> 7632 T1980 C90 P58743 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7632 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 4
[junit4:junit4]   2> 7635 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1412754092095176704)]} 0 0
[junit4:junit4]   2> 7640 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7641 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111]} 0 3
[junit4:junit4]   2> 7649 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112 (1412754092103565312)]} 0 6
[junit4:junit4]   2> 7655 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7656 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[112]} 0 3
[junit4:junit4]   2> 7661 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113 (1412754092120342528)]} 0 2
[junit4:junit4]   2> 7666 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7667 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[113]} 0 3
[junit4:junit4]   2> 7669 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114 (1412754092130828288)]} 0 0
[junit4:junit4]   2> 7674 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7675 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[114]} 0 3
[junit4:junit4]   2> 7678 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115 (1412754092140265472)]} 0 0
[junit4:junit4]   2> 7681 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[115 (1412754092143411200)]} 0 0
[junit4:junit4]   2> 7684 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116 (1412754092146556928)]} 0 0
[junit4:junit4]   2> 7687 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[116 (1412754092149702656)]} 0 0
[junit4:junit4]   2> 7691 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117 (1412754092153896960)]} 0 0
[junit4:junit4]   2> 7696 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7697 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[117]} 0 3
[junit4:junit4]   2> 7699 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118 (1412754092162285568)]} 0 0
[junit4:junit4]   2> 7705 T1980 C90 P58743 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7706 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[118]} 0 3
[junit4:junit4]   2> 7708 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119 (1412754092171722752)]} 0 0
[junit4:junit4]   2> 7713 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7714 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[119]} 0 3
[junit4:junit4]   2> 7716 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120 (1412754092180111360)]} 0 0
[junit4:junit4]   2> 7721 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7722 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 3
[junit4:junit4]   2> 7724 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121 (1412754092188499968)]} 0 0
[junit4:junit4]   2> 7730 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7731 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[121]} 0 3
[junit4:junit4]   2> 7739 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122 (1412754092197937152)]} 0 6
[junit4:junit4]   2> 7751 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 7751 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[122]} 0 10
[junit4:junit4]   2> 7757 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1412754092221005824)]} 0 2
[junit4:junit4]   2> 7760 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1412754092225200128)]} 0 1
[junit4:junit4]   2> 7762 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124 (1412754092228345856)]} 0 0
[junit4:junit4]   2> 7768 T1980 C90 P58743 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7768 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[124]} 0 3
[junit4:junit4]   2> 7771 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125 (1412754092237783040)]} 0 0
[junit4:junit4]   2> 7779 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7779 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[125]} 0 4
[junit4:junit4]   2> 7782 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126 (1412754092249317376)]} 0 0
[junit4:junit4]   2> 7786 T1974 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7788 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7789 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[126]} 0 4
[junit4:junit4]   2> 7793 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127 (1412754092259803136)]} 0 1
[junit4:junit4]   2> 7795 T1973 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7795 T1999 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7797 T1987 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7797 T2011 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2>  C91_STATE=coll:collection1 core:collection1 props:{shard=shard5, leader=true, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:58751_solr, base_url=http://127.0.0.1:58751/solr}
[junit4:junit4]   2> 7808 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 7809 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[127]} 0 12
[junit4:junit4]   2> 7811 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128 (1412754092279726080)]} 0 0
[junit4:junit4]   2> 7816 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[128 (1412754092282871808)]} 0 2
[junit4:junit4]   2> 7819 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129 (1412754092288114688)]} 0 0
[junit4:junit4]   2> 7825 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7826 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[129]} 0 4
[junit4:junit4]   2> 7838 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130 (1412754092297551872)]} 0 10
[junit4:junit4]   2> 7841 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[130 (1412754092311183360)]} 0 0
[junit4:junit4]   2> 7844 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131 (1412754092314329088)]} 0 0
[junit4:junit4]   2> 7847 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[131 (1412754092317474816)]} 0 0
[junit4:junit4]   2> 7856 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132 (1412754092321669120)]} 0 5
[junit4:junit4]   2> 7860 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[132 (1412754092331106304)]} 0 0
[junit4:junit4]   2> 7865 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133 (1412754092334252032)]} 0 2
[junit4:junit4]   2> 7868 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[133 (1412754092339494912)]} 0 0
[junit4:junit4]   2> 7871 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134 (1412754092342640640)]} 0 0
[junit4:junit4]   2> 7877 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7878 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[134]} 0 3
[junit4:junit4]   2> 7881 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135 (1412754092353126400)]} 0 0
[junit4:junit4]   2> 7885 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[135 (1412754092357320704)]} 0 0
[junit4:junit4]   2> 7888 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136 (1412754092360466432)]} 0 0
[junit4:junit4]   2> 7894 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7894 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[136]} 0 3
[junit4:junit4]   2> 7897 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137 (1412754092369903616)]} 0 0
[junit4:junit4]   2> 7901 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[137 (1412754092374097920)]} 0 0
[junit4:junit4]   2> 7904 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138 (1412754092377243648)]} 0 0
[junit4:junit4]   2> 7907 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[138 (1412754092380389376)]} 0 0
[junit4:junit4]   2> 7910 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139 (1412754092383535104)]} 0 0
[junit4:junit4]   2> 7917 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7917 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[139]} 0 4
[junit4:junit4]   2> 7920 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140 (1412754092394020864)]} 0 0
[junit4:junit4]   2> 7926 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7927 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[140]} 0 3
[junit4:junit4]   2> 7930 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141 (1412754092404506624)]} 0 0
[junit4:junit4]   2> 7935 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7936 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[141]} 0 3
[junit4:junit4]   2> 7944 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142 (1412754092412895232)]} 0 6
[junit4:junit4]   2> 7950 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7951 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[142]} 0 3
[junit4:junit4]   2> 7956 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143 (1412754092429672448)]} 0 2
[junit4:junit4]   2> 7961 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7961 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[143]} 0 3
[junit4:junit4]   2> 7964 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144 (1412754092440158208)]} 0 0
[junit4:junit4]   2> 7970 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7970 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[144]} 0 3
[junit4:junit4]   2> 7974 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145 (1412754092450643968)]} 0 0
[junit4:junit4]   2> 7980 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7981 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[145]} 0 3
[junit4:junit4]   2> 7983 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146 (1412754092460081152)]} 0 0
[junit4:junit4]   2> 8011 T1991 C92 P58747 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58751/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 8011 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[146]} 0 25
[junit4:junit4]   2> 8014 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147 (1412754092492587008)]} 0 0
[junit4:junit4]   2> 8019 T2003 C91 P58751 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58743/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8020 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[147]} 0 3
[junit4:junit4]   2> 8024 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148 (1412754092503072768)]} 0 0
[junit4:junit4]   2> 8032 T1980 C90 P58743 REQ [collection1] webapp=/solr path=/update params={distrib.from=http://127.0.0.1:58747/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8032 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[148]} 0 5
[junit4:junit4]   2> 8037 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149 (1412754092515655680)]} 0 1
[junit4:junit4]   2> 8039 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[149 (1412754092518801408)]} 0 0
[junit4:junit4]   2> 8042 T1965 C89 P58739 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8190 T1965 C89 P58739 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f214c5; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\control1\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@f214c5; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_5_nrm.cfe, _3_nrm.cfe, _5.fdt, _5.prx, _1.frq, _4.prx, _4.fnm, _4_nrm.cfe, _3.fdt, _1.fnm, _2.tim, _4.fdx, _5.tip, _5.tim, _4.frq, _0.prx, _0.fnm, _2.prx, _2.frq, _2.fdt, _3.prx, _2.fdx, _5.si, _2.tip, _1.fdx, _1.prx, _3_nrm.cfs, _5_nrm.cfs, _1.fdt, _0.tim, _0.frq, _4_nrm.cfs, _3.si, _6.fdt, _0.tip, _2.si, _3.frq, _6.frq, _3.tim, _6.fdx, _3.fnm, _2_nrm.cfe, _6.tip, _6_nrm.cfs, _6.tim, _4.tip, _0.si, _2_nrm.cfs, _1.tip, _1.tim, _1_nrm.cfs, _5.fnm, _4.tim, _6_nrm.cfe, _5.frq, _4.si, _4.fdt, _0_nrm.cfs, _1_nrm.cfe, _2.fnm, _3.fdx, _6.prx, _1.si, _0_nrm.cfe, _5.fdx, segments_2, _6.si, _6.fnm, _0.fdx, _3.tip, _0.fdt]
[junit4:junit4]   2> 8191 T1965 C89 P58739 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 8196 T1965 C89 P58739 oass.SolrIndexSearcher.<init> Opening Searcher@14c6572 main
[junit4:junit4]   2> 8197 T1965 C89 P58739 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8198 T1976 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14c6572 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> 8198 T1965 C89 P58739 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 156
[junit4:junit4]   2> 8201 T1979 C90 P58743 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8256 T1979 C90 P58743 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d89c7a; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty2\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@1d89c7a; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_1_nrm.cfs, _0.fnm, _1.frq, _0_nrm.cfs, _1.fnm, _1_nrm.cfe, _1.fdx, _1.prx, _1.fdt, _1.si, _0_nrm.cfe, _0.tim, _0.frq, segments_2, _0.fdx, _0.si, _1.tip, _0.tip, _0.prx, _1.tim, _0.fdt]
[junit4:junit4]   2> 8257 T1979 C90 P58743 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 8263 T1979 C90 P58743 oass.SolrIndexSearcher.<init> Opening Searcher@1d0d90e main
[junit4:junit4]   2> 8264 T1979 C90 P58743 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8266 T1988 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d0d90e main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C8)}
[junit4:junit4]   2> 8267 T1979 C90 P58743 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:58747/solr/collection1/, StdNode: http://127.0.0.1:58751/solr/collection1/]
[junit4:junit4]   2> 8270 T1991 C92 P58747 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8270 T2003 C91 P58751 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8357 T2003 C91 P58751 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@11f3d09; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty4\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@11f3d09; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_1_nrm.cfs, _0.fnm, _1.frq, _0_nrm.cfs, _1.fnm, _1_nrm.cfe, _1.fdx, _1.prx, _1.fdt, _1.si, _0_nrm.cfe, _0.tim, _0.frq, segments_2, _0.fdx, _0.si, _1.tip, _0.tip, _0.prx, _1.tim, _0.fdt]
[junit4:junit4]   2> 8358 T2003 C91 P58751 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 8368 T2003 C91 P58751 oass.SolrIndexSearcher.<init> Opening Searcher@1a5f638 main
[junit4:junit4]   2> 8369 T2003 C91 P58751 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8371 T2012 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a5f638 main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 8371 T2003 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 101
[junit4:junit4]   2> 8378 T1991 C92 P58747 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@13daabc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\jetty3\collection1\data\index lockFactory=org.apache.lucene.store.NativeFSLockFactory@13daabc; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_2.si, _1.frq, _1.fnm, _2.tim, _2_nrm.cfe, _2_nrm.cfs, _0.si, _0.prx, _1.tip, _1.tim, _1_nrm.cfs, _0.fnm, _2.prx, _2.fdt, _2.frq, _0_nrm.cfs, _2.fdx, _2.fnm, _1_nrm.cfe, _1.prx, _1.fdx, _2.tip, _1.fdt, _1.si, _0.frq, _0.tim, _0_nrm.cfe, segments_2, _0.fdx, _0.tip, _0.fdt]
[junit4:junit4]   2> 8379 T1991 C92 P58747 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 8385 T1991 C92 P58747 oass.SolrIndexSearcher.<init> Opening Searcher@17edea3 main
[junit4:junit4]   2> 8385 T1991 C92 P58747 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8387 T2000 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17edea3 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10)}
[junit4:junit4]   2> 8388 T1991 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 118
[junit4:junit4]   2> 8389 T1979 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 188
[junit4:junit4]   2> 8392 T1992 C92 P58747 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8394 T1992 C92 P58747 oass.SolrIndexSearcher.<init> Opening Searcher@805b25 main
[junit4:junit4]   2> 8395 T1992 C92 P58747 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8397 T2000 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@805b25 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10)}
[junit4:junit4]   2> 8398 T1992 C92 P58747 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:58743/solr/collection1/, StdNode: http://127.0.0.1:58751/solr/collection1/]
[junit4:junit4]   2> 8400 T2003 C91 P58751 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8400 T1980 C90 P58743 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8403 T2003 C91 P58751 oass.SolrIndexSearcher.<init> Opening Searcher@13ce58f main
[junit4:junit4]   2> 8404 T1980 C90 P58743 oass.SolrIndexSearcher.<init> Opening Searcher@d43b75 main
[junit4:junit4]   2> 8404 T2003 C91 P58751 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8404 T1980 C90 P58743 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8405 T1988 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> 8409 T2012 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13ce58f main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 8413 T2003 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 13
[junit4:junit4]   2> 8413 T1988 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d43b75 main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C8)}
[junit4:junit4]   2> 8414 T1980 C90 P58743 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 14
[junit4:junit4]   2> 8415 T1992 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 23
[junit4:junit4]   2> 8417 T2004 C91 P58751 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8418 T2004 C91 P58751 oass.SolrIndexSearcher.<init> Opening Searcher@684999 main
[junit4:junit4]   2> 8419 T2004 C91 P58751 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8421 T2012 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@684999 main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C9)}
[junit4:junit4]   2> 8422 T2004 C91 P58751 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:58743/solr/collection1/, StdNode: http://127.0.0.1:58747/solr/collection1/]
[junit4:junit4]   2> 8424 T1980 C90 P58743 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8424 T1991 C92 P58747 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8424 T1980 C90 P58743 oass.SolrIndexSearcher.<init> Opening Searcher@10908e4 main
[junit4:junit4]   2> 8425 T1980 C90 P58743 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8425 T1991 C92 P58747 oass.SolrIndexSearcher.<init> Opening Searcher@67a7e2 main
[junit4:junit4]   2> 8426 T1991 C92 P58747 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8427 T1988 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10908e4 main{StandardDirectoryReader(segments_2:5:nrt _0(5.0):C10 _1(5.0):C8)}
[junit4:junit4]   2> 8427 T1980 C90 P58743 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> 8429 T2000 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67a7e2 main{StandardDirectoryReader(segments_2:7:nrt _0(5.0):C10 _1(5.0):C10 _2(5.0):C10)}
[junit4:junit4]   2> 8435 T1991 C92 P58747 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 11
[junit4:junit4]   2> 8436 T2004 C91 P58751 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 19
[junit4:junit4]   2> 8438 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_ti1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8440 T1953 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 8448 T1953 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58732/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@c635bf
[junit4:junit4]   2> 8450 T2019 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58732
[junit4:junit4]   2> 8450 T1953 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8452 T2019 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58732, initiating session
[junit4:junit4]   2> 8452 T1955 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58764
[junit4:junit4]   2> 8452 T1955 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58764
[junit4:junit4]   2> 8455 T1957 oazs.NIOServerCnxn.finishSessionInit Established session 0x139b1c5aba20006 with negotiated timeout 10000 for client /127.0.0.1:58764
[junit4:junit4]   2> 8455 T2019 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58732, sessionid = 0x139b1c5aba20006, negotiated timeout = 10000
[junit4:junit4]   2> 8455 T2020 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c635bf name:ZooKeeperConnection Watcher:127.0.0.1:58732/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8456 T1953 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8457 T1953 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8470 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293858&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8471 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293858&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=1 
[junit4:junit4]   2> 8473 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293858&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=1 
[junit4:junit4]   2> 8476 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293858&q=*:*&ids=134,6,111,107&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8477 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293858&q=*:*&ids=126,139&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8476 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293858&q=*:*&ids=132,138,108,124&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8478 T1993 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_ti1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=14 
[junit4:junit4]   2> 8481 T1965 C89 P58739 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> 8489 T1982 C90 P58743 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:58743/solr/collection1/&NOW=1347307293878&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8490 T1995 C92 P58747 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:58747/solr/collection1/&NOW=1347307293878&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8493 T2006 C91 P58751 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:58751/solr/collection1/&NOW=1347307293878&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8496 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293878&q=*:*&ids=130,141&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8496 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293878&q=*:*&ids=16,11&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8497 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293878&q=*:*&ids=128,121,136,106,104,131&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8498 T1979 C90 P58743 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=14 
[junit4:junit4]   2> 8501 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_f1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8506 T2005 C91 P58751 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:58751/solr/collection1/&NOW=1347307293899&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8506 T1994 C92 P58747 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:58747/solr/collection1/&NOW=1347307293899&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8507 T1981 C90 P58743 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:58743/solr/collection1/&NOW=1347307293899&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 8509 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293899&q=*:*&ids=117,7,113,129&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8509 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293899&q=*:*&ids=132,1,148&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8511 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293899&q=*:*&ids=147,112,149&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8512 T1992 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=7 
[junit4:junit4]   2> 8514 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_f1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8519 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293911&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8519 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293911&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8519 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293911&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8522 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293911&q=*:*&ids=134,144,122,105,119&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8522 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293911&q=*:*&ids=16,100&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8522 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293911&q=*:*&ids=121,5,137&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8523 T1992 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_f1+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=6 
[junit4:junit4]   2> 8526 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tf1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8532 T2005 C91 P58751 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:58751/solr/collection1/&NOW=1347307293924&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8532 T1994 C92 P58747 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:58747/solr/collection1/&NOW=1347307293924&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8532 T1981 C90 P58743 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:58743/solr/collection1/&NOW=1347307293924&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8535 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293924&q=*:*&ids=126,121,17,131&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8535 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293924&q=*:*&ids=7&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8535 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293924&q=*:*&ids=135,1,10,8,11&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8536 T1992 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=6 
[junit4:junit4]   2> 8539 T1965 C89 P58739 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> 8545 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347307293936&shard.url=127.0.0.1:58747/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8545 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347307293936&shard.url=127.0.0.1:58743/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8545 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_tf1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347307293936&shard.url=127.0.0.1:58751/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8551 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293936&q=*:*&ids=138,109,14&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8551 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293936&q=*:*&ids=146,113,120,129&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8551 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293936&q=*:*&ids=116,104,103&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8552 T1983 C90 P58743 REQ [collection1] webapp=/solr path=/select params={collection=collection1&sort=n_tf1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=10 
[junit4:junit4]   2> 8555 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_d1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8565 T1996 C92 P58747 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:58747/solr/collection1/&NOW=1347307293953&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8565 T1984 C90 P58743 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:58743/solr/collection1/&NOW=1347307293953&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=1 
[junit4:junit4]   2> 8566 T2007 C91 P58751 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:58751/solr/collection1/&NOW=1347307293953&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8569 T2007 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293953&q=*:*&ids=116,139&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8569 T1996 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293953&q=*:*&ids=105,9,129,130,119&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8570 T1984 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293953&q=*:*&ids=124,16,14&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8570 T2004 C91 P58751 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> 8574 T1965 C89 P58739 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> 8579 T2007 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293971&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8579 T1996 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293971&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8579 T1984 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293971&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8582 T2007 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293971&q=*:*&ids=145,137,17,106&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8582 T1996 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293971&q=*:*&ids=133,122,120&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8583 T1984 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293971&q=*:*&ids=13,118,11&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8584 T2004 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_d1+asc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=7 
[junit4:junit4]   2> 8587 T1965 C89 P58739 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> 8593 T1981 C90 P58743 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:58743/solr/collection1/&NOW=1347307293985&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8593 T2005 C91 P58751 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:58751/solr/collection1/&NOW=1347307293985&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8593 T1994 C92 P58747 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:58747/solr/collection1/&NOW=1347307293985&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8596 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307293985&q=*:*&ids=10,138,148,14,100&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8596 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293985&q=*:*&ids=149&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8596 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293985&q=*:*&ids=117,9,15,102&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8597 T1992 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_td1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=6 
[junit4:junit4]   2> 8600 T1965 C89 P58739 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> 8606 T2007 C91 P58751 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:58751/solr/collection1/&NOW=1347307293998&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8606 T1996 C92 P58747 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:58747/solr/collection1/&NOW=1347307293998&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8606 T1984 C90 P58743 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:58743/solr/collection1/&NOW=1347307293998&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8609 T1996 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307293998&q=*:*&ids=125,146,107,105,120,141&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8609 T2007 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307293998&q=*:*&ids=147,121,104,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8610 T2004 C91 P58751 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=6 
[junit4:junit4]   2> 8613 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_l1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8618 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347307294010&shard.url=127.0.0.1:58747/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8618 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347307294010&shard.url=127.0.0.1:58743/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8618 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_l1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347307294010&shard.url=127.0.0.1:58751/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8622 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294010&q=*:*&ids=143,133,120,129,101,141&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8622 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294010&q=*:*&ids=121,106&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8622 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294010&q=*:*&ids=16,13&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8623 T1993 C92 P58747 REQ [collection1] webapp=/solr path=/select params={collection=collection1&sort=n_l1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=7 
[junit4:junit4]   2> 8626 T1965 C89 P58739 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> 8630 T1995 C92 P58747 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:58747/solr/collection1/&NOW=1347307294022&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8630 T2006 C91 P58751 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:58751/solr/collection1/&NOW=1347307294022&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8630 T1982 C90 P58743 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:58743/solr/collection1/&NOW=1347307294022&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8633 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294022&q=*:*&ids=100&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8633 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294022&q=*:*&ids=3,2,122,105,15,102,119&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8633 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294022&q=*:*&ids=126,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8634 T1979 C90 P58743 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=6 
[junit4:junit4]   2> 8637 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8641 T1984 C90 P58743 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:58743/solr/collection1/&NOW=1347307294034&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8641 T2007 C91 P58751 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:58751/solr/collection1/&NOW=1347307294034&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8641 T1996 C92 P58747 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:58747/solr/collection1/&NOW=1347307294034&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8644 T2007 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294034&q=*:*&ids=116,147,131&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8644 T1996 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294034&q=*:*&ids=134,4,111,105,130,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8644 T1984 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294034&q=*:*&ids=148&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8645 T2004 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=5 
[junit4:junit4]   2> 8648 T1965 C89 P58739 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> 8653 T1984 C90 P58743 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:58743/solr/collection1/&NOW=1347307294045&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8653 T2007 C91 P58751 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:58751/solr/collection1/&NOW=1347307294045&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8653 T1996 C92 P58747 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:58747/solr/collection1/&NOW=1347307294045&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8656 T1984 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294045&q=*:*&ids=108,16,118,100&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8656 T1996 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294045&q=*:*&ids=125,133,15,120,101&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8656 T2007 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294045&q=*:*&ids=121&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8657 T2004 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=6 
[junit4:junit4]   2> 8659 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_dt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8664 T1996 C92 P58747 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:58747/solr/collection1/&NOW=1347307294056&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8664 T2007 C91 P58751 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:58751/solr/collection1/&NOW=1347307294056&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8664 T1984 C90 P58743 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:58743/solr/collection1/&NOW=1347307294056&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8668 T1996 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294056&q=*:*&ids=125,2,120&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8668 T2007 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294056&q=*:*&ids=127,128,5,106,17,131&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8668 T1984 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294056&q=*:*&ids=132&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8669 T2004 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=7 
[junit4:junit4]   2> 8672 T1965 C89 P58739 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> 8676 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294068&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8676 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294068&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8676 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294068&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8679 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294068&q=*:*&ids=114,113,122&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8679 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294068&q=*:*&ids=126,121,137,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8679 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294068&q=*:*&ids=138,8,118&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8681 T1993 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_dt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=7 
[junit4:junit4]   2> 8684 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tdt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8688 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294081&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8688 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294081&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8688 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294081&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8692 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294081&q=*:*&ids=126,128&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8692 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294081&q=*:*&ids=143,2,113,120&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8693 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294081&q=*:*&ids=135,124,16,14&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8694 T1979 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=n_tdt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=7 
[junit4:junit4]   2> 8696 T1965 C89 P58739 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> 8701 T1982 C90 P58743 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:58743/solr/collection1/&NOW=1347307294093&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8701 T2006 C91 P58751 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:58751/solr/collection1/&NOW=1347307294093&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8701 T1995 C92 P58747 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:58747/solr/collection1/&NOW=1347307294093&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8704 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294093&q=*:*&ids=10,109&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8704 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294093&q=*:*&ids=144,114,133,6&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8704 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294093&q=*:*&ids=116,106,131,142&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8705 T1983 C90 P58743 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=6 
[junit4:junit4]   2> 8708 T1965 C89 P58739 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> 8713 T1984 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294105&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8713 T2007 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294105&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8713 T1996 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294105&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8717 T2007 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294105&q=*:*&ids=5&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8717 T1996 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294105&q=*:*&ids=3,2,7,9,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8724 T1984 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294105&q=*:*&ids=10,1,8,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8725 T2004 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=14 
[junit4:junit4]   2> 8728 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=a_si+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8732 T1995 C92 P58747 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:58747/solr/collection1/&NOW=1347307294125&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8732 T2006 C91 P58751 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:58751/solr/collection1/&NOW=1347307294125&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8732 T1982 C90 P58743 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:58743/solr/collection1/&NOW=1347307294125&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8735 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294125&q=*:*&ids=1,13,11&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8735 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294125&q=*:*&ids=3,2,7,6,4,9,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8736 T1979 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=a_si+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=5 
[junit4:junit4]   2> 8738 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&fl=*,score&q=*:*&distrib=false&wt=javabin&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8743 T1996 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294135&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8743 T1984 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294135&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8743 T2007 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294135&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8746 T2007 C91 P58751 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294135&q=*:*&ids=5&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8746 T1984 C90 P58743 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294135&q=*:*&ids=10,1,8,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8746 T1996 C92 P58747 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294135&q=*:*&ids=3,2,7,9,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8747 T2004 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=a_si+desc&fl=*,score&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=6 
[junit4:junit4]   2> 8749 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&fl=*,score&q=*:*&distrib=false&wt=javabin&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8754 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294146&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8754 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294146&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8754 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294146&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8757 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294146&q=*:*&ids=125,133,15,120,101&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8757 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294146&q=*:*&ids=121&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8757 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294146&q=*:*&ids=108,16,118,100&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8758 T1983 C90 P58743 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+asc&fl=*,score&shards=shard3,shard4,shard5&q=*:*&wt=javabin&version=2} hits=67 status=0 QTime=6 
[junit4:junit4]   2> 8761 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8765 T2005 C91 P58751 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:58751/solr/collection1/&NOW=1347307294157&start=0&q=*:*&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8765 T1981 C90 P58743 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:58743/solr/collection1/&NOW=1347307294157&start=0&q=*:*&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8765 T1994 C92 P58747 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:58747/solr/collection1/&NOW=1347307294157&start=0&q=*:*&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8768 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294157&q=*:*&ids=116,147,131&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8768 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294157&q=*:*&ids=148&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8768 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294157&q=*:*&ids=134,4,111,105,130,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8769 T1993 C92 P58747 REQ [collection1] webapp=/solr path=/select params={sort=n_tl1+desc&shards=shard3,shard4,shard5&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=6 
[junit4:junit4]   2> 8772 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&wt=javabin&q={!func}a_si&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8777 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={fl=id,score&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294169&start=0&q={!func}a_si&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8777 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={fl=id,score&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294169&start=0&q={!func}a_si&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8777 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={fl=id,score&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294169&start=0&q={!func}a_si&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8779 T1994 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294169&q={!func}a_si&ids=3,2,7,9,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8779 T1981 C90 P58743 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294169&q={!func}a_si&ids=10,1,8,13&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8779 T2005 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294169&q={!func}a_si&ids=5&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8780 T1993 C92 P58747 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q={!func}a_si&version=2} hits=67 status=0 QTime=5 
[junit4:junit4]   2> 8783 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&fl=*,score&wt=javabin&q={!func}a_si&version=2} hits=67 status=0 QTime=0 
[junit4:junit4]   2> 8788 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294180&start=0&q={!func}a_si&isShard=true&fsv=true} hits=18 status=0 QTime=0 
[junit4:junit4]   2> 8788 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294180&start=0&q={!func}a_si&isShard=true&fsv=true} hits=19 status=0 QTime=0 
[junit4:junit4]   2> 8788 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id,score&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294180&start=0&q={!func}a_si&isShard=true&fsv=true} hits=30 status=0 QTime=0 
[junit4:junit4]   2> 8791 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294180&q={!func}a_si&ids=5&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8791 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294180&q={!func}a_si&ids=10,1,8,13&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8791 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={fl=*,score&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294180&q={!func}a_si&ids=3,2,7,9,12&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8792 T1983 C90 P58743 REQ [collection1] webapp=/solr path=/select params={collection=collection1&fl=*,score&wt=javabin&q={!func}a_si&version=2} hits=67 status=0 QTime=6 
[junit4:junit4]   2> 8794 T1965 C89 P58739 REQ [collection1] webapp=/solr path=/select params={distrib=false&wt=javabin&q=quick&version=2} hits=2 status=0 QTime=0 
[junit4:junit4]   2> 8799 T1982 C90 P58743 REQ [collection1] webapp=/solr path=/select params={fl=id,score&shard.url=127.0.0.1:58743/solr/collection1/&NOW=1347307294191&start=0&q=quick&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 8799 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={fl=id,score&shard.url=127.0.0.1:58751/solr/collection1/&NOW=1347307294191&start=0&q=quick&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 8799 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={fl=id,score&shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294191&start=0&q=quick&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=1 status=0 QTime=0 
[junit4:junit4]   2> 8803 T1995 C92 P58747 REQ [collection1] webapp=/solr path=/select params={shard.url=127.0.0.1:58747/solr/collection1/&NOW=1347307294191&q=quick&ids=4&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 8803 T2006 C91 P58751 REQ [collection1] webapp=/solr path=/select params={shard.url=1

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

.java:42)
[junit4:junit4]   2> 	at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:734)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:178)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:175)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:175)
[junit4:junit4]   2> 	at org.apache.solr.cloud.ElectionContext.cancelElection(ElectionContext.java:62)
[junit4:junit4]   2> 	at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:208)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:159)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:103)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:57)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:132)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> 
[junit4:junit4]   2> 21120 T1999 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 21120 T1999 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 21122 T1999 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection3/leader_elect/shard1/election
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> 	at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1249)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:278)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:275)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:275)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:91)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:57)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:132)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> 
[junit4:junit4]   2> 21123 T1999 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 21123 T1999 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 21123 T1999 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 21125 T1953 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> 21242 T2020 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c635bf name:ZooKeeperConnection Watcher:127.0.0.1:58732/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 21242 T1953 oaz.ZooKeeper.close Session: 0x139b1c5aba20006 closed
[junit4:junit4]   2> 21242 T2020 oascc.ConnectionManager.process zkClient has disconnected
[junit4:junit4]   2> 21243 T2020 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 21246 T1953 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 22883 T1952 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4:junit4]   2> 43106 T1952 ccr.ThreadLeakControl.checkThreadLeaks SEVERE 1 thread leaked from SUITE scope at org.apache.solr.cloud.BasicDistributedZkTest: 
[junit4:junit4]   2> 	   1) Thread[id=1956, name=SessionTracker, state=TIMED_WAITING, group=TGRP-BasicDistributedZkTest]
[junit4:junit4]   2> 	        at java.lang.Object.wait(Native Method)
[junit4:junit4]   2> 	        at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:145)
[junit4:junit4]   2> 43106 T1952 ccr.ThreadLeakControl.tryToInterruptAll Starting to interrupt leaked threads:
[junit4:junit4]   2> 	   1) Thread[id=1956, name=SessionTracker, state=TIMED_WAITING, group=TGRP-BasicDistributedZkTest]
[junit4:junit4]   2> 43107 T1956 oazs.SessionTrackerImpl.run SEVERE Unexpected interruption java.lang.InterruptedException
[junit4:junit4]   2> 	at java.lang.Object.wait(Native Method)
[junit4:junit4]   2> 	at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:145)
[junit4:junit4]   2> 
[junit4:junit4]   2> 43107 T1956 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> 43109 T1952 ccr.ThreadLeakControl.tryToInterruptAll All leaked threads terminated.
[junit4:junit4]   2> NOTE: test params are: codec=Appending, sim=DefaultSimilarity, locale=mt, timezone=Asia/Omsk
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_07 (32-bit)/cpus=2,threads=1,free=18793472,total=169959424
[junit4:junit4]   2> NOTE: All tests run in this JVM: [PingRequestHandlerTest, DisMaxRequestHandlerTest, ClusterStateTest, PolyFieldTest, TestSolrCoreProperties, ScriptEngineTest, UpdateRequestProcessorFactoryTest, TestStressRecovery, TestLRUCache, WordBreakSolrSpellCheckerTest, TestOmitPositions, QueryEqualityTest, DateFieldTest, CurrencyFieldTest, SolrRequestParserTest, MinimalSchemaTest, SolrCoreTest, JSONWriterTest, ZkNodePropsTest, NumericFieldsTest, SOLR749Test, LegacyHTMLStripCharFilterTest, TestBinaryResponseWriter, TestSolrDeletionPolicy2, TestWordDelimiterFilterFactory, IndexReaderFactoryTest, FileUtilsTest, SuggesterFSTTest, RequestHandlersTest, BadIndexSchemaTest, LeaderElectionTest, ResourceLoaderTest, DocumentBuilderTest, StatelessScriptUpdateProcessorFactoryTest, TestBM25SimilarityFactory, PathHierarchyTokenizerFactoryTest, TestSurroundQueryParser, TestNumberUtils, TestLMJelinekMercerSimilarityFactory, DistanceFunctionTest, TestJmxMonitoredMap, TestPropInject, JsonLoaderTest, TestMergePolicyConfig, MoreLikeThisHandlerTest, TestFastWriter, SpatialFilterTest, FastVectorHighlighterTest, TestStressLucene, BasicZkTest, TestFaceting, TestDocumentBuilder, RequiredFieldsTest, TestPseudoReturnFields, OverseerTest, TestDistributedSearch, SolrPluginUtilsTest, DirectSolrSpellCheckerTest, TestUpdate, TestLFUCache, DefaultValueUpdateProcessorTest, TestLMDirichletSimilarityFactory, DebugComponentTest, SimplePostToolTest, FileBasedSpellCheckerTest, TestWriterPerf, SolrCoreCheckLockOnStartupTest, FullSolrCloudDistribCmdsTest, TestJoin, CSVRequestHandlerTest, TestIndexSearcher, TestBinaryField, TestUtils, CoreContainerCoreInitFailuresTest, TestCoreContainer, CacheHeaderTest, TestRangeQuery, ShowFileRequestHandlerTest, TestQuerySenderListener, TestFunctionQuery, TestStressVersions, TestDocSet, TestDFRSimilarityFactory, LeaderElectionIntegrationTest, FieldMutatingUpdateProcessorTest, RAMDirectoryFactoryTest, TestSolrJ, DistributedSpellCheckComponentTest, IndexSchemaTest, TestFastOutputStream, TestRTGBase, TestValueSourceCache, TestPHPSerializedResponseWriter, TestLuceneMatchVersion, TestStressReorder, IndexSchemaRuntimeFieldTest, DistributedTermsComponentTest, SpellCheckComponentTest, FieldAnalysisRequestHandlerTest, BinaryUpdateRequestHandlerTest, CircularListTest, AlternateDirectoryTest, ClusterStateUpdateTest, TestQueryUtils, TestSearchPerf, TestSolrXMLSerializer, BadComponentTest, SyncSliceTest, TestQueryTypes, TestRandomFaceting, BasicDistributedZkTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=BasicDistributedZkTest -Dtests.seed=44E11591A5BB0316 -Dtests.slow=true -Dtests.locale=mt -Dtests.timezone=Asia/Omsk -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR   0.00s | BasicDistributedZkTest (suite) <<<
[junit4:junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.BasicDistributedZkTest: 
[junit4:junit4]    >    1) Thread[id=1956, name=SessionTracker, state=TIMED_WAITING, group=TGRP-BasicDistributedZkTest]
[junit4:junit4]    >         at java.lang.Object.wait(Native Method)
[junit4:junit4]    >         at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:145)
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([44E11591A5BB0316]:0)
[junit4:junit4] Completed in 43.20s, 1 test, 1 error <<< FAILURES!

[...truncated 353 lines...]
BUILD FAILED
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\build.xml:29: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:146: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\lucene\module-build.xml:63: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1133: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:810: There were test failures: 228 suites, 920 tests, 1 suite-level error, 12 ignored (7 assumptions)

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



Mime
View raw message