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-Linux (32bit/jdk1.6.0_33) - Build # 179 - Still Failing!
Date Sun, 05 Aug 2012 00:02:54 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/179/
Java: 32bit/jdk1.6.0_33 -server -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.cloud.SyncSliceTest.testDistribSearch

Error Message:
No live SolrServers available to handle this request

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request
	at __randomizedtesting.SeedInfo.seed([3B5E138D934449EE:BAB89D95E41B29D2]:0)
	at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:322)
	at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:237)
	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:179)
	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:155)
	at org.apache.solr.cloud.FullSolrCloudTest.commit(FullSolrCloudTest.java:1477)
	at org.apache.solr.cloud.SyncSliceTest.waitForThingsToLevelOut(SyncSliceTest.java:268)
	at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:162)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:679)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1995)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$1100(RandomizedRunner.java:132)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:818)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:877)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:891)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:32)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:825)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$700(RandomizedRunner.java:132)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3$1.run(RandomizedRunner.java:671)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:697)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:736)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:747)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:38)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleNoInstanceHooksOverrides$1.evaluate(TestRuleNoInstanceHooksOverrides.java:53)
	at org.apache.lucene.util.TestRuleNoStaticHooksShadowing$1.evaluate(TestRuleNoStaticHooksShadowing.java:52)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:36)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:605)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$400(RandomizedRunner.java:132)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.run(RandomizedRunner.java:551)




Build Log:
[...truncated 6457 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.SyncSliceTest
[junit4:junit4] ERROR   21.5s J0 | SyncSliceTest.testDistribSearch
[junit4:junit4]    > Throwable #1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([3B5E138D934449EE:BAB89D95E41B29D2]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:322)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:237)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:179)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:155)
[junit4:junit4]    > 	at org.apache.solr.cloud.FullSolrCloudTest.commit(FullSolrCloudTest.java:1477)
[junit4:junit4]    > 	at org.apache.solr.cloud.SyncSliceTest.waitForThingsToLevelOut(SyncSliceTest.java:268)
[junit4:junit4]    > 	at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:162)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:679)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:597)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1995)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$1100(RandomizedRunner.java:132)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:818)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:877)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:891)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:32)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:825)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$700(RandomizedRunner.java:132)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3$1.run(RandomizedRunner.java:671)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:697)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:736)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:747)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:38)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleNoInstanceHooksOverrides$1.evaluate(TestRuleNoInstanceHooksOverrides.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleNoStaticHooksShadowing$1.evaluate(TestRuleNoStaticHooksShadowing.java:52)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:605)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$400(RandomizedRunner.java:132)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.run(RandomizedRunner.java:551)
[junit4:junit4]    > 
[junit4:junit4]   2> 3 T889 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/./solrtest-SyncSliceTest-1344124743396
[junit4:junit4]   2> 3 T889 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T890 oazs.ZooKeeperServer.setTickTime tickTime set to 3000
[junit4:junit4]   2> 4 T890 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 4 T890 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 104 T889 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36284 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@142c789
[junit4:junit4]   2> 104 T895 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:36284
[junit4:junit4]   2> 105 T891 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:36764
[junit4:junit4]   2> 105 T895 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36284, initiating session
[junit4:junit4]   2> 105 T891 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:36764
[junit4:junit4]   2> 106 T893 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 181 T893 oazs.NIOServerCnxn.finishSessionInit Established session 0x138f413eee60000 with negotiated timeout 10000 for client /127.0.0.1:36764
[junit4:junit4]   2> 181 T895 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:36284, sessionid = 0x138f413eee60000, negotiated timeout = 10000
[junit4:junit4]   2> 181 T896 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@142c789 name:ZooKeeperConnection Watcher:127.0.0.1:36284 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 182 T889 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 184 T894 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138f413eee60000
[junit4:junit4]   2> 185 T896 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 185 T889 oaz.ZooKeeper.close Session: 0x138f413eee60000 closed
[junit4:junit4]   2> 185 T889 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36284/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@198a407
[junit4:junit4]   2> 186 T891 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36764 which had sessionid 0x138f413eee60000
[junit4:junit4]   2> 186 T897 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:36284
[junit4:junit4]   2> 186 T897 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36284, initiating session
[junit4:junit4]   2> 186 T891 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:36765
[junit4:junit4]   2> 186 T891 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:36765
[junit4:junit4]   2> 187 T893 oazs.NIOServerCnxn.finishSessionInit Established session 0x138f413eee60001 with negotiated timeout 10000 for client /127.0.0.1:36765
[junit4:junit4]   2> 187 T897 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:36284, sessionid = 0x138f413eee60001, negotiated timeout = 10000
[junit4:junit4]   2> 187 T898 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@198a407 name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 187 T889 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 189 T889 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 190 T889 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 192 T889 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 193 T889 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 196 T889 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 198 T889 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 200 T889 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 201 T889 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 203 T889 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 204 T889 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 206 T889 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 207 T889 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 209 T889 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 210 T894 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138f413eee60001
[junit4:junit4]   2> 210 T891 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36765 which had sessionid 0x138f413eee60001
[junit4:junit4]   2> 210 T898 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 210 T889 oaz.ZooKeeper.close Session: 0x138f413eee60001 closed
[junit4:junit4]   2> 211 T889 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 213 T889 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:34700
[junit4:junit4]   2> 213 T889 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 214 T889 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 214 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 221 T889 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 221 T889 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 221 T889 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 222 T889 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 222 T889 oasc.CoreContainer.<init> New CoreContainer 30241313
[junit4:junit4]   2> 222 T889 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 222 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 232 T889 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 241 T889 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36284/solr
[junit4:junit4]   2> 241 T889 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36284/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@9d4f7b
[junit4:junit4]   2> 242 T908 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:36284
[junit4:junit4]   2> 242 T891 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:36766
[junit4:junit4]   2> 243 T908 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36284, initiating session
[junit4:junit4]   2> 243 T891 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:36766
[junit4:junit4]   2> 243 T893 oazs.NIOServerCnxn.finishSessionInit Established session 0x138f413eee60002 with negotiated timeout 8000 for client /127.0.0.1:36766
[junit4:junit4]   2> 243 T908 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:36284, sessionid = 0x138f413eee60002, negotiated timeout = 8000
[junit4:junit4]   2> 244 T909 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9d4f7b name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 244 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 245 T889 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 246 T889 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34700_solr
[junit4:junit4]   2> 246 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:34700_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:34700_solr
[junit4:junit4]   2> 247 T889 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34700_solr
[junit4:junit4]   2> 248 T889 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 248 T889 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 248 T889 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 248 T889 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 249 T889 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 249 T889 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 249 T889 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 249 T889 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 249 T889 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 250 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 251 T889 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 254 T889 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 255 T889 oasc.Overseer.start Overseer (id=88088559183331330-127.0.0.1:34700_solr-n_0000000000) starting
[junit4:junit4]   2> 255 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60002 type:create cxid:0x19 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 256 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 256 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 257 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 260 T911 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 260 T889 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 262 T889 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 263 T910 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 267 T889 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 267 T889 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 267 T889 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 268 T889 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 268 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 268 T889 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 269 T889 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 371 T889 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 499 T889 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 499 T889 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 507 T889 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 921 T889 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 929 T889 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 933 T889 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 943 T889 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 946 T889 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 949 T889 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 950 T889 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 950 T889 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1344124743396/control/data/
[junit4:junit4]   2> 950 T889 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19da967
[junit4:junit4]   2> 953 T889 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1344124743396/control/data/index/
[junit4:junit4]   2> 953 T889 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1344124743396/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 955 T889 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 956 T889 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 956 T889 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 957 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 957 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 957 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 958 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 958 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 958 T889 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 959 T889 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 959 T889 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 959 T889 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 959 T889 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 960 T889 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 960 T889 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 960 T889 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 960 T889 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 961 T889 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 961 T889 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 961 T889 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 961 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 962 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 962 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 962 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 962 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 962 T889 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 963 T889 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 963 T889 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 964 T889 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 965 T889 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 965 T889 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> 968 T889 oass.SolrIndexSearcher.<init> Opening Searcher@c6d115 main
[junit4:junit4]   2> 968 T889 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 968 T889 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 969 T889 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 971 T889 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 983 T912 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c6d115 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 984 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60002 type:create cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 985 T889 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 985 T889 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:34700/solr shardId:control_shard
[junit4:junit4]   2> 986 T889 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 990 T889 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 993 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60002 type:create cxid:0x69 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1266 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1267 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60002 type:create cxid:0x74 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1271 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1295 T889 oasc.ZkController.register We are http://127.0.0.1:34700/solr/collection1/ and leader is http://127.0.0.1:34700/solr/collection1/
[junit4:junit4]   2> 1295 T889 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34700/solr
[junit4:junit4]   2> 1295 T889 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 1297 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1297 T889 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0
[junit4:junit4]   2> 1298 T889 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1298 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1301 T889 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1302 T889 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:40379
[junit4:junit4]   2> 1303 T889 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1304 T889 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 1304 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 1314 T889 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1315 T889 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1315 T889 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 1315 T889 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 1316 T889 oasc.CoreContainer.<init> New CoreContainer 16130663
[junit4:junit4]   2> 1316 T889 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 1316 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 1331 T889 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1344 T889 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36284/solr
[junit4:junit4]   2> 1344 T889 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36284/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1a084d7
[junit4:junit4]   2> 1345 T922 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:36284
[junit4:junit4]   2> 1346 T922 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36284, initiating session
[junit4:junit4]   2> 1346 T891 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:36768
[junit4:junit4]   2> 1346 T891 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:36768
[junit4:junit4]   2> 1346 T893 oazs.NIOServerCnxn.finishSessionInit Established session 0x138f413eee60003 with negotiated timeout 8000 for client /127.0.0.1:36768
[junit4:junit4]   2> 1346 T922 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:36284, sessionid = 0x138f413eee60003, negotiated timeout = 8000
[junit4:junit4]   2> 1347 T923 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1a084d7 name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1347 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1347 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1348 T889 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40379_solr
[junit4:junit4]   2> 1349 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:40379_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:40379_solr
[junit4:junit4]   2> 1349 T889 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:40379_solr
[junit4:junit4]   2> 1350 T909 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 1350 T889 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1351 T889 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1351 T889 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1351 T889 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1352 T889 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1352 T889 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1352 T889 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1352 T889 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1353 T889 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1353 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1357 T889 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1361 T889 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 1361 T889 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 1361 T889 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1361 T889 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 1362 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 1362 T889 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 1363 T889 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1387 T889 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 1433 T889 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1434 T889 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1438 T889 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1775 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1777 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1777 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 1787 T889 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1793 T889 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1796 T889 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1805 T889 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1808 T889 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1812 T889 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1813 T889 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1814 T889 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty1/
[junit4:junit4]   2> 1814 T889 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19da967
[junit4:junit4]   2> 1815 T889 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty1/index/
[junit4:junit4]   2> 1815 T889 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1818 T889 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1818 T889 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1819 T889 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1820 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1820 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1821 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1821 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1822 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1822 T889 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1822 T889 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1823 T889 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1823 T889 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1823 T889 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1824 T889 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1824 T889 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1825 T889 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1825 T889 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1825 T889 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1825 T889 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1826 T889 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1826 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1827 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1827 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1827 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1828 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1828 T889 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1828 T889 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1829 T889 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1830 T889 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1831 T889 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1832 T889 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> 1836 T889 oass.SolrIndexSearcher.<init> Opening Searcher@1ff2e2f main
[junit4:junit4]   2> 1836 T889 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1837 T889 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1837 T889 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1842 T889 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1858 T924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ff2e2f main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2280 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2282 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2282 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2360 T889 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 2360 T889 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:40379/solr shardId:shard1
[junit4:junit4]   2> 2361 T889 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 2365 T889 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 2368 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60003 type:create cxid:0x4b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2784 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2788 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2788 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2819 T889 oasc.ZkController.register We are http://127.0.0.1:40379/solr/collection1/ and leader is http://127.0.0.1:40379/solr/collection1/
[junit4:junit4]   2> 2820 T889 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:40379/solr
[junit4:junit4]   2> 2820 T889 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2821 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2822 T889 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0
[junit4:junit4]   2> 2822 T889 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2822 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2824 T889 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2825 T889 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:60507
[junit4:junit4]   2> 2826 T889 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2826 T889 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 2826 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 2833 T889 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2834 T889 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2834 T889 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 2834 T889 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 2834 T889 oasc.CoreContainer.<init> New CoreContainer 18916081
[junit4:junit4]   2> 2834 T889 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 2835 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 2844 T889 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2852 T889 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36284/solr
[junit4:junit4]   2> 2853 T889 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36284/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@5abdb2
[junit4:junit4]   2> 2853 T934 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:36284
[junit4:junit4]   2> 2854 T891 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:36770
[junit4:junit4]   2> 2854 T934 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36284, initiating session
[junit4:junit4]   2> 2854 T891 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:36770
[junit4:junit4]   2> 2855 T893 oazs.NIOServerCnxn.finishSessionInit Established session 0x138f413eee60004 with negotiated timeout 8000 for client /127.0.0.1:36770
[junit4:junit4]   2> 2855 T934 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:36284, sessionid = 0x138f413eee60004, negotiated timeout = 8000
[junit4:junit4]   2> 2855 T935 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5abdb2 name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2855 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2856 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2856 T889 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60507_solr
[junit4:junit4]   2> 2857 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60507_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60507_solr
[junit4:junit4]   2> 2857 T889 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60507_solr
[junit4:junit4]   2> 2858 T923 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2858 T909 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 2859 T889 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2859 T889 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2859 T889 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2859 T889 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2859 T889 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2859 T889 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2860 T889 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2860 T889 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2860 T889 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2860 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2863 T889 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2867 T889 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 2867 T889 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2867 T889 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2868 T889 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2868 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 2869 T889 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 2869 T889 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2894 T889 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2936 T889 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2937 T889 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2942 T889 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3291 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3294 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3294 T935 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3294 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3298 T889 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3304 T889 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3307 T889 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3314 T889 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3317 T889 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3319 T889 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3320 T889 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3321 T889 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/
[junit4:junit4]   2> 3321 T889 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19da967
[junit4:junit4]   2> 3322 T889 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index/
[junit4:junit4]   2> 3322 T889 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3324 T889 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3325 T889 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 3325 T889 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3326 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3326 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3326 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3327 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3327 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3327 T889 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3328 T889 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3328 T889 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3328 T889 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3328 T889 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3329 T889 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3329 T889 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3329 T889 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3329 T889 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3330 T889 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3330 T889 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3330 T889 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3331 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3331 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3331 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3331 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3332 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3332 T889 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3332 T889 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3332 T889 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3333 T889 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3334 T889 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3335 T889 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> 3337 T889 oass.SolrIndexSearcher.<init> Opening Searcher@10bb634 main
[junit4:junit4]   2> 3337 T889 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3337 T889 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3338 T889 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3341 T889 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3353 T936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10bb634 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3798 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3800 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3800 T935 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3800 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3854 T889 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3854 T889 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:60507/solr shardId:shard1
[junit4:junit4]   2> 3856 T889 oasc.ZkController.register We are http://127.0.0.1:60507/solr/collection1/ and leader is http://127.0.0.1:40379/solr/collection1/
[junit4:junit4]   2> 3857 T889 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60507/solr
[junit4:junit4]   2> 3857 T889 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C95 name=collection1 org.apache.solr.core.SolrCore@1ec34e0 url=http://127.0.0.1:60507/solr/collection1 node=127.0.0.1:60507_solr
[junit4:junit4]   2> 3858 T937 C95 P60507 oasc.RecoveryStrategy.run Starting recovery process. recoveringAfterStartup=true
[junit4:junit4]   2> 3858 T937 C95 P60507 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 3858 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3859 T937 C95 P60507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3859 T889 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3859 T889 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3859 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3861 T889 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3862 T889 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:47590
[junit4:junit4]   2> 3863 T889 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3863 T889 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 3863 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 3870 T889 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3870 T889 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3870 T889 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 3871 T889 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 3871 T889 oasc.CoreContainer.<init> New CoreContainer 3580484
[junit4:junit4]   2> 3871 T889 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 3871 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 3880 T889 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3888 T889 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36284/solr
[junit4:junit4]   2> 3888 T889 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36284/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@1980b94
[junit4:junit4]   2> 3889 T947 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:36284
[junit4:junit4]   2> 3889 T947 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36284, initiating session
[junit4:junit4]   2> 3890 T891 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:36773
[junit4:junit4]   2> 3890 T891 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:36773
[junit4:junit4]   2> 3890 T893 oazs.NIOServerCnxn.finishSessionInit Established session 0x138f413eee60005 with negotiated timeout 8000 for client /127.0.0.1:36773
[junit4:junit4]   2> 3890 T947 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:36284, sessionid = 0x138f413eee60005, negotiated timeout = 8000
[junit4:junit4]   2> 3891 T948 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1980b94 name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3891 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3892 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3892 T889 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47590_solr
[junit4:junit4]   2> 3893 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:47590_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:47590_solr
[junit4:junit4]   2> 3893 T889 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:47590_solr
[junit4:junit4]   2> 3894 T935 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3894 T923 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3894 T909 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3895 T889 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3895 T889 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3895 T889 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3895 T889 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3896 T889 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3896 T889 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3896 T889 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3896 T889 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3896 T889 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3897 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3900 T889 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3904 T889 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 3904 T889 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3905 T889 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3905 T889 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3905 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 3906 T889 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 3906 T889 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3930 T889 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 3976 T889 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3977 T889 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3981 T889 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4302 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4305 T948 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4305 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4305 T935 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4305 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4333 T889 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4339 T889 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4342 T889 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4349 T889 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4352 T889 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4354 T889 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4355 T889 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4356 T889 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/
[junit4:junit4]   2> 4356 T889 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19da967
[junit4:junit4]   2> 4356 T889 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index/
[junit4:junit4]   2> 4357 T889 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4359 T889 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4359 T889 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4359 T889 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4360 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4360 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4360 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4361 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4361 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4361 T889 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4362 T889 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4362 T889 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4362 T889 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4362 T889 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4363 T889 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4363 T889 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4363 T889 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4363 T889 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4363 T889 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4364 T889 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4364 T889 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4364 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4364 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4365 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4365 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4365 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4365 T889 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4366 T889 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4366 T889 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4366 T889 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4368 T889 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4368 T889 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> 4370 T889 oass.SolrIndexSearcher.<init> Opening Searcher@1cf4da3 main
[junit4:junit4]   2> 4370 T889 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4371 T889 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4371 T889 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4373 T889 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4387 T949 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cf4da3 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4812 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4815 T935 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4815 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4815 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4815 T948 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 4888 T889 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4888 T889 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:47590/solr shardId:shard1
[junit4:junit4]   2> 4891 T889 oasc.ZkController.register We are http://127.0.0.1:47590/solr/collection1/ and leader is http://127.0.0.1:40379/solr/collection1/
[junit4:junit4]   2> 4891 T889 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:47590/solr
[junit4:junit4]   2> 4891 T889 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C96 name=collection1 org.apache.solr.core.SolrCore@1655fe4 url=http://127.0.0.1:47590/solr/collection1 node=127.0.0.1:47590_solr
[junit4:junit4]   2> 4892 T950 C96 P47590 oasc.RecoveryStrategy.run Starting recovery process. recoveringAfterStartup=true
[junit4:junit4]   2> 4893 T950 C96 P47590 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 4892 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4893 T950 C96 P47590 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4893 T889 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0
[junit4:junit4]   2> 4894 T889 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4894 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4896 T889 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4897 T889 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:48775
[junit4:junit4]   2> 4897 T889 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4897 T889 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 4898 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 4904 T889 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4904 T889 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4905 T889 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 4905 T889 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 4905 T889 oasc.CoreContainer.<init> New CoreContainer 32935721
[junit4:junit4]   2> 4905 T889 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 4905 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 4914 T889 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4922 T889 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:36284/solr
[junit4:junit4]   2> 4922 T889 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36284/solr sessionTimeout=8000 watcher=org.apache.solr.common.cloud.ConnectionManager@48ec58
[junit4:junit4]   2> 4923 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:36284
[junit4:junit4]   2> 4923 T960 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36284, initiating session
[junit4:junit4]   2> 4924 T891 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:36775
[junit4:junit4]   2> 4924 T891 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:36775
[junit4:junit4]   2> 4924 T893 oazs.NIOServerCnxn.finishSessionInit Established session 0x138f413eee60006 with negotiated timeout 8000 for client /127.0.0.1:36775
[junit4:junit4]   2> 4925 T960 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:36284, sessionid = 0x138f413eee60006, negotiated timeout = 8000
[junit4:junit4]   2> 4925 T961 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@48ec58 name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4925 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4926 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4926 T889 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48775_solr
[junit4:junit4]   2> 4927 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:48775_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:48775_solr
[junit4:junit4]   2> 4927 T889 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48775_solr
[junit4:junit4]   2> 4928 T948 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4928 T923 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4928 T935 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4928 T909 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 4929 T889 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4929 T889 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4929 T889 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4929 T889 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4930 T889 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4930 T889 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4930 T889 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4930 T889 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4930 T889 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4931 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4933 T889 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4937 T889 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 4937 T889 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4938 T889 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4938 T889 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4939 T889 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 4939 T889 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 4939 T889 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4966 T889 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5007 T889 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5008 T889 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5012 T889 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5317 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5319 T948 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5319 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5319 T935 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5319 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5319 T961 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5361 T889 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5367 T889 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5369 T889 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5377 T889 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5379 T889 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5381 T889 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5382 T889 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5383 T889 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty4/
[junit4:junit4]   2> 5383 T889 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19da967
[junit4:junit4]   2> 5383 T889 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty4/index/
[junit4:junit4]   2> 5384 T889 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5386 T889 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty4/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5386 T889 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5386 T889 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5387 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5387 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5387 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5388 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5388 T889 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5388 T889 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5388 T889 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5389 T889 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5389 T889 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5389 T889 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5389 T889 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5390 T889 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5390 T889 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5390 T889 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5390 T889 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5391 T889 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5391 T889 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5391 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5391 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5392 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5392 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5392 T889 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5392 T889 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5392 T889 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5393 T889 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5393 T889 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5394 T889 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5395 T889 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> 5397 T889 oass.SolrIndexSearcher.<init> Opening Searcher@1995e3e main
[junit4:junit4]   2> 5397 T889 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5397 T889 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5398 T889 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5400 T889 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5414 T962 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1995e3e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5823 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5826 T948 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5826 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5826 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5826 T935 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5826 T961 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5915 T889 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5915 T889 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:48775/solr shardId:shard1
[junit4:junit4]   2> 5917 T889 oasc.ZkController.register We are http://127.0.0.1:48775/solr/collection1/ and leader is http://127.0.0.1:40379/solr/collection1/
[junit4:junit4]   2> 5917 T889 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48775/solr
[junit4:junit4]   2> 5918 T889 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> ASYNC  NEW_CORE C97 name=collection1 org.apache.solr.core.SolrCore@1db5c17 url=http://127.0.0.1:48775/solr/collection1 node=127.0.0.1:48775_solr
[junit4:junit4]   2> 5919 T963 C97 P48775 oasc.RecoveryStrategy.run Starting recovery process. recoveringAfterStartup=true
[junit4:junit4]   2> 5919 T963 C97 P48775 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 5919 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5919 T963 C97 P48775 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5919 T889 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0
[junit4:junit4]   2> 5920 T889 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5920 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5922 T889 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36284/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@17b0c1b
[junit4:junit4]   2> 5922 T964 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:36284
[junit4:junit4]   2> 5923 T964 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36284, initiating session
[junit4:junit4]   2> 5923 T891 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:36778
[junit4:junit4]   2> 5923 T891 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:36778
[junit4:junit4]   2> 5924 T893 oazs.NIOServerCnxn.finishSessionInit Established session 0x138f413eee60007 with negotiated timeout 10000 for client /127.0.0.1:36778
[junit4:junit4]   2> 5924 T964 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:36284, sessionid = 0x138f413eee60007, negotiated timeout = 10000
[junit4:junit4]   2> 5924 T965 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17b0c1b name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5925 T889 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5926 T889 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:false
[junit4:junit4]   2> 5926 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 5928 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5928 T889 oasc.AbstractDistributedZkTestCase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):360
[junit4:junit4]   2> 5929 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6329 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6331 T948 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6331 T965 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6331 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6331 T961 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6331 T935 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6331 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6930 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7931 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8932 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9934 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C95_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:60507_solr, base_url=http://127.0.0.1:60507/solr}
[junit4:junit4]   2> 10862 T937 C95 P60507 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:40379/solr/collection1/ recoveringAfterStartup=true
[junit4:junit4]   2> 10862 T937 C95 P60507 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:60507/solr START replicas=[http://127.0.0.1:40379/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 10862 T937 C95 P60507 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication
[junit4:junit4]   2> 10862 T937 C95 P60507 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery
[junit4:junit4]   2> 10862 T937 C95 P60507 oasc.RecoveryStrategy.doRecovery Begin buffering updates
[junit4:junit4]   2> 10862 T937 C95 P60507 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 10863 T937 C95 P60507 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:40379/solr/collection1/
[junit4:junit4]   2> 10863 T937 C95 P60507 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C98 name=collection1 org.apache.solr.core.SolrCore@4148d4 url=http://127.0.0.1:40379/solr/collection1 node=127.0.0.1:40379_solr
[junit4:junit4]   2> 10866 T918 C98 P40379 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=1 
[junit4:junit4]   2> 10868 T919 C98 P40379 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10870 T919 C98 P40379 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10870 T919 C98 P40379 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10870 T919 C98 P40379 oass.SolrIndexSearcher.<init> Opening Searcher@191d6ad main
[junit4:junit4]   2> 10871 T919 C98 P40379 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10871 T924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@191d6ad main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 10871 T919 C98 P40379 /update {waitSearcher=true&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 3
[junit4:junit4]   2> 10871 T937 C95 P60507 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 10872 T937 C95 P60507 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 10872 T920 C98 P40379 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 10873 T920 C98 P40379 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 10874 T937 C95 P60507 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10874 T937 C95 P60507 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 10874 T937 C95 P60507 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10887 T937 C95 P60507 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 10887 T937 C95 P60507 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 10887 T937 C95 P60507 oass.SolrIndexSearcher.<init> Opening Searcher@495713 main
[junit4:junit4]   2> 10888 T937 C95 P60507 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10888 T936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@495713 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 10888 T937 C95 P60507 oasc.RecoveryStrategy.replay No replay needed
[junit4:junit4]   2> 10888 T937 C95 P60507 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active
[junit4:junit4]   2> 10897 T937 C95 P60507 oasc.RecoveryStrategy.doRecovery Finished recovery process
[junit4:junit4]   2> 10935 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11345 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11349 T948 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11349 T965 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11349 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11350 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11350 T961 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11350 T935 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2>  C96_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:47590_solr, base_url=http://127.0.0.1:47590/solr}
[junit4:junit4]   2> 11896 T950 C96 P47590 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:40379/solr/collection1/ recoveringAfterStartup=true
[junit4:junit4]   2> 11896 T950 C96 P47590 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:47590/solr START replicas=[http://127.0.0.1:40379/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 11897 T950 C96 P47590 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication
[junit4:junit4]   2> 11897 T950 C96 P47590 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery
[junit4:junit4]   2> 11897 T950 C96 P47590 oasc.RecoveryStrategy.doRecovery Begin buffering updates
[junit4:junit4]   2> 11897 T950 C96 P47590 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 11897 T950 C96 P47590 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:40379/solr/collection1/
[junit4:junit4]   2> 11897 T950 C96 P47590 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 11898 T918 C98 P40379 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 11899 T921 C98 P40379 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11901 T921 C98 P40379 oass.SolrIndexSearcher.<init> Opening Searcher@259429 main
[junit4:junit4]   2> 11901 T921 C98 P40379 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11902 T924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@259429 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 11902 T921 C98 P40379 /update {waitSearcher=true&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 3
[junit4:junit4]   2> 11902 T950 C96 P47590 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 11903 T950 C96 P47590 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 11904 T920 C98 P40379 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11905 T950 C96 P47590 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 11906 T950 C96 P47590 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 11906 T950 C96 P47590 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11917 T950 C96 P47590 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 11917 T950 C96 P47590 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 11918 T950 C96 P47590 oass.SolrIndexSearcher.<init> Opening Searcher@f3a603 main
[junit4:junit4]   2> 11918 T950 C96 P47590 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11918 T949 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f3a603 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 11919 T950 C96 P47590 oasc.RecoveryStrategy.replay No replay needed
[junit4:junit4]   2> 11919 T950 C96 P47590 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active
[junit4:junit4]   2> 11920 T950 C96 P47590 oasc.RecoveryStrategy.doRecovery Finished recovery process
[junit4:junit4]   2> 11936 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12354 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12357 T948 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 12357 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 12357 T965 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 12357 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 12357 T935 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 12357 T961 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2>  C97_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:48775_solr, base_url=http://127.0.0.1:48775/solr}
[junit4:junit4]   2> 12922 T963 C97 P48775 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:40379/solr/collection1/ recoveringAfterStartup=true
[junit4:junit4]   2> 12923 T963 C97 P48775 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:48775/solr START replicas=[http://127.0.0.1:40379/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 12923 T963 C97 P48775 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication
[junit4:junit4]   2> 12923 T963 C97 P48775 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery
[junit4:junit4]   2> 12923 T963 C97 P48775 oasc.RecoveryStrategy.doRecovery Begin buffering updates
[junit4:junit4]   2> 12923 T963 C97 P48775 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 12923 T963 C97 P48775 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:40379/solr/collection1/
[junit4:junit4]   2> 12923 T963 C97 P48775 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12924 T918 C98 P40379 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 12925 T915 C98 P40379 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12926 T915 C98 P40379 oass.SolrIndexSearcher.<init> Opening Searcher@5de64a main
[junit4:junit4]   2> 12926 T915 C98 P40379 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12926 T924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5de64a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 12926 T915 C98 P40379 /update {waitSearcher=true&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 1
[junit4:junit4]   2> 12927 T963 C97 P48775 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 12927 T963 C97 P48775 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 12928 T920 C98 P40379 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 12930 T963 C97 P48775 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty4/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12930 T963 C97 P48775 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 12930 T963 C97 P48775 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12937 T963 C97 P48775 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty4/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty4/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 12937 T963 C97 P48775 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 12937 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12938 T963 C97 P48775 oass.SolrIndexSearcher.<init> Opening Searcher@a2e9a main
[junit4:junit4]   2> 12938 T963 C97 P48775 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12938 T962 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a2e9a main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 12939 T963 C97 P48775 oasc.RecoveryStrategy.replay No replay needed
[junit4:junit4]   2> 12939 T963 C97 P48775 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active
[junit4:junit4]   2> 12940 T963 C97 P48775 oasc.RecoveryStrategy.doRecovery Finished recovery process
[junit4:junit4]   2> 13361 T910 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13363 T948 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13363 T961 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13363 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13363 T935 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13363 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13363 T965 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13938 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C99 name=collection1 org.apache.solr.core.SolrCore@19cb8 url=http://127.0.0.1:34700/solr/collection1 node=127.0.0.1:34700_solr
[junit4:junit4]   2> 13947 T901 C99 P34700 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13948 T901 C99 P34700 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13948 T901 C99 P34700 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 13949 T901 C99 P34700 oass.SolrIndexSearcher.<init> Opening Searcher@102d246 main
[junit4:junit4]   2> 13949 T901 C99 P34700 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13950 T912 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@102d246 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 13950 T901 C99 P34700 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 3
[junit4:junit4]   2> 13950 T889 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:36284/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@3b1929
[junit4:junit4]   2> 13951 T969 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:36284
[junit4:junit4]   2> 13951 T969 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:36284, initiating session
[junit4:junit4]   2> 13951 T891 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:36797
[junit4:junit4]   2> 13952 T891 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:36797
[junit4:junit4]   2> 13952 T893 oazs.NIOServerCnxn.finishSessionInit Established session 0x138f413eee60008 with negotiated timeout 10000 for client /127.0.0.1:36797
[junit4:junit4]   2> 13952 T969 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:36284, sessionid = 0x138f413eee60008, negotiated timeout = 10000
[junit4:junit4]   2> 13952 T970 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3b1929 name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 13953 T889 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 13954 T919 C98 P40379 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13955 T919 C98 P40379 oass.SolrIndexSearcher.<init> Opening Searcher@138159 main
[junit4:junit4]   2> 13955 T919 C98 P40379 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13956 T924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@138159 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2>  C97_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:48775_solr, base_url=http://127.0.0.1:48775/solr}
[junit4:junit4]   2> 13961 T953 C97 P48775 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2>  C95_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:60507_solr, base_url=http://127.0.0.1:60507/solr}
[junit4:junit4]   2> 13961 T927 C95 P60507 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13963 T953 C97 P48775 oass.SolrIndexSearcher.<init> Opening Searcher@42b765 main
[junit4:junit4]   2> 13963 T953 C97 P48775 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13963 T927 C95 P60507 oass.SolrIndexSearcher.<init> Opening Searcher@a9bd1d main
[junit4:junit4]   2> 13963 T962 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@42b765 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 13963 T927 C95 P60507 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13963 T936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a9bd1d main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 13963 T953 C97 P48775 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 2
[junit4:junit4]   2> 13964 T927 C95 P60507 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 3
[junit4:junit4]   2>  C96_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:47590_solr, base_url=http://127.0.0.1:47590/solr}
[junit4:junit4]   2> 13965 T940 C96 P47590 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13966 T940 C96 P47590 oass.SolrIndexSearcher.<init> Opening Searcher@1f709d6 main
[junit4:junit4]   2> 13966 T940 C96 P47590 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13966 T949 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f709d6 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 13966 T940 C96 P47590 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 1
[junit4:junit4]   2> 13967 T919 C98 P40379 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 13
[junit4:junit4]   2> 13967 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13968 T916 C98 P40379 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 13969 T928 C95 P60507 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 13970 T941 C96 P47590 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 13971 T954 C97 P48775 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 15974 T901 C99 P34700 oass.SolrIndexSearcher.<init> Opening Searcher@a49a8b realtime
[junit4:junit4]   2> 15974 T901 C99 P34700 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1409416963676962816)} 0 1
[junit4:junit4]   2> 15976 T919 C98 P40379 oass.SolrIndexSearcher.<init> Opening Searcher@fce23e realtime
[junit4:junit4]   2> 15978 T927 C95 P60507 oass.SolrIndexSearcher.<init> Opening Searcher@10bcdd9 realtime
[junit4:junit4]   2> 15978 T953 C97 P48775 oass.SolrIndexSearcher.<init> Opening Searcher@492d3b realtime
[junit4:junit4]   2> 15978 T927 C95 P60507 REQ /update {update.distrib=FROMLEADER&_version_=-1409416963680108544&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 15978 T940 C96 P47590 oass.SolrIndexSearcher.<init> Opening Searcher@7dc5aa realtime
[junit4:junit4]   2> 15978 T953 C97 P48775 REQ /update {update.distrib=FROMLEADER&_version_=-1409416963680108544&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 15978 T940 C96 P47590 REQ /update {update.distrib=FROMLEADER&_version_=-1409416963680108544&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 15979 T919 C98 P40379 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1409416963680108544)} 0 3
[junit4:junit4]   2> 15981 T901 C99 P34700 /update {wt=javabin&version=2} {add=[0 (1409416963684302848)]} 0 1
[junit4:junit4]   2> 15985 T927 C95 P60507 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15985 T953 C97 P48775 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15985 T940 C96 P47590 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15986 T919 C98 P40379 /update {wt=javabin&version=2} {add=[0 (1409416963687448576)]} 0 3
[junit4:junit4]   2> 15987 T901 C99 P34700 /update {wt=javabin&version=2} {add=[1 (1409416963691642880)]} 0 0
[junit4:junit4]   2> 15991 T940 C96 P47590 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 15991 T953 C97 P48775 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 15991 T927 C95 P60507 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 15991 T919 C98 P40379 /update {wt=javabin&version=2} {add=[1 (1409416963693740032)]} 0 2
[junit4:junit4]   2> 15993 T901 C99 P34700 /update {wt=javabin&version=2} {add=[2 (1409416963697934336)]} 0 0
[junit4:junit4]   2> 15997 T953 C97 P48775 REQ /update {update.distrib=FROMLEADER&test.distrib.skip.servers=http://127.0.0.1:60507/solr/collection1/&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 15997 T940 C96 P47590 REQ /update {update.distrib=FROMLEADER&test.distrib.skip.servers=http://127.0.0.1:60507/solr/collection1/&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 15997 T919 C98 P40379 /update {test.distrib.skip.servers=http://127.0.0.1:60507/solr/collection1/&wt=javabin&version=2} {add=[2 (1409416963700031488)]} 0 2
[junit4:junit4]   2> 15999 T901 C99 P34700 /update {wt=javabin&version=2} {add=[3 (1409416963704225792)]} 0 0
[junit4:junit4]   2> 16003 T953 C97 P48775 REQ /update {update.distrib=FROMLEADER&test.distrib.skip.servers=http://127.0.0.1:60507/solr/collection1/&test.distrib.skip.servers=http://127.0.0.1:47590/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16003 T919 C98 P40379 /update {test.distrib.skip.servers=http://127.0.0.1:60507/solr/collection1/&test.distrib.skip.servers=http://127.0.0.1:47590/solr/collection1/&wt=javabin&version=2} {add=[3 (1409416963706322944)]} 0 2
[junit4:junit4]   2> 16005 T901 C99 P34700 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16017 T901 C99 P34700 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/control/data/index,segFN=segments_2,generation=2,filenames=[_0_Asserting_0.tim, _0.fnm, _0_Asserting_0.tip, _0_MockRandom_0.prx, _0_nrm.cfs, _0_Direct_0.frq, _0_Asserting_0.prx, _0_Asserting_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockRandom_0.tip, _0_SimpleText_0.pst, _0_nrm.cfe, segments_2, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0.si, _0_MockRandom_0.tim, _0_Direct_0.tim, _0.fdt]
[junit4:junit4]   2> 16017 T901 C99 P34700 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 16018 T901 C99 P34700 oass.SolrIndexSearcher.<init> Opening Searcher@c45c0e main
[junit4:junit4]   2> 16019 T901 C99 P34700 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16019 T912 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c45c0e main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C4)}
[junit4:junit4]   2> 16020 T901 C99 P34700 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 16
[junit4:junit4]   2> 16021 T919 C98 P40379 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16037 T919 C98 P40379 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty1/index,segFN=segments_2,generation=2,filenames=[_0_Asserting_0.tim, _0.fnm, _0_Asserting_0.tip, _0_MockRandom_0.prx, _0_nrm.cfs, _0_Direct_0.frq, _0_Asserting_0.prx, _0_Asserting_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockRandom_0.tip, _0_SimpleText_0.pst, _0_nrm.cfe, segments_2, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0.si, _0_MockRandom_0.tim, _0_Direct_0.tim, _0.fdt]
[junit4:junit4]   2> 16037 T919 C98 P40379 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 16038 T919 C98 P40379 oass.SolrIndexSearcher.<init> Opening Searcher@1d999a3 main
[junit4:junit4]   2> 16039 T919 C98 P40379 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16039 T924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d999a3 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C4)}
[junit4:junit4]   2> 16040 T953 C97 P48775 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16040 T927 C95 P60507 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16040 T940 C96 P47590 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16067 T927 C95 P60507 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index,segFN=segments_3,generation=3,filenames=[_0_Asserting_0.tim, _0_MockRandom_0.tib, _0.fnm, _0_Asserting_0.tip, _0_MockRandom_0.prx, _0_nrm.cfs, _0_Direct_0.frq, _0_Asserting_0.prx, _0_Asserting_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockRandom_0.tiv, _0_SimpleText_0.pst, _0_nrm.cfe, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0.si, _0_Direct_0.tim, _0.fdt, segments_3]
[junit4:junit4]   2> 16067 T927 C95 P60507 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 16069 T940 C96 P47590 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index,segFN=segments_3,generation=3,filenames=[_0_Asserting_0.tim, _0_MockRandom_0.tib, _0.fnm, _0_Asserting_0.tip, _0_MockRandom_0.prx, _0_nrm.cfs, _0_Direct_0.frq, _0_Asserting_0.prx, _0_Asserting_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_SimpleText_0.pst, _0_nrm.cfe, _0_MockRandom_0.tii, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0.si, _0_Direct_0.tim, _0.fdt, segments_3]
[junit4:junit4]   2> 16069 T927 C95 P60507 oass.SolrIndexSearcher.<init> Opening Searcher@1c8de42 main
[junit4:junit4]   2> 16069 T940 C96 P47590 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 16070 T936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c8de42 main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C2)}
[junit4:junit4]   2> 16070 T927 C95 P60507 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16070 T927 C95 P60507 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 30
[junit4:junit4]   2> 16071 T940 C96 P47590 oass.SolrIndexSearcher.<init> Opening Searcher@192fb95 main
[junit4:junit4]   2> 16071 T940 C96 P47590 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16072 T949 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@192fb95 main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C3)}
[junit4:junit4]   2> 16072 T940 C96 P47590 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 32
[junit4:junit4]   2> 16087 T953 C97 P48775 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty4/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty4/index,segFN=segments_3,generation=3,filenames=[_0_Asserting_0.tim, _0_MockRandom_0.tib, _0.fnm, _0_Asserting_0.tip, _0_nrm.cfs, _0_Direct_0.frq, _0_MockRandom_0.skp, _0_Asserting_0.prx, _0_Asserting_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockRandom_0.tiv, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _0_SimpleText_0.pst, _0_nrm.cfe, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0.si, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0.fdt, segments_3]
[junit4:junit4]   2> 16087 T953 C97 P48775 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 16088 T953 C97 P48775 oass.SolrIndexSearcher.<init> Opening Searcher@1512137 main
[junit4:junit4]   2> 16089 T953 C97 P48775 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16089 T962 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1512137 main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C4)}
[junit4:junit4]   2> 16089 T953 C97 P48775 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 49
[junit4:junit4]   2> 16090 T919 C98 P40379 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 69
[junit4:junit4]   2> 16090 T889 oasc.AbstractDistributedZkTestCase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):360
[junit4:junit4]   2> 16091 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> check const of shard1
[junit4:junit4]   2> client0
[junit4:junit4]   2> PROPS:shard=shard1
[junit4:junit4]   2> leader=true
[junit4:junit4]   2> roles=null
[junit4:junit4]   2> state=active
[junit4:junit4]   2> core=collection1
[junit4:junit4]   2> collection=collection1
[junit4:junit4]   2> node_name=127.0.0.1:40379_solr
[junit4:junit4]   2> base_url=http://127.0.0.1:40379/solr
[junit4:junit4]   2> 
[junit4:junit4]   2> 16093 T916 C98 P40379 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   2>  live:true
[junit4:junit4]   2>  num:4
[junit4:junit4]   2> 
[junit4:junit4]   2> client1
[junit4:junit4]   2> PROPS:shard=shard1
[junit4:junit4]   2> roles=null
[junit4:junit4]   2> state=active
[junit4:junit4]   2> core=collection1
[junit4:junit4]   2> collection=collection1
[junit4:junit4]   2> node_name=127.0.0.1:60507_solr
[junit4:junit4]   2> base_url=http://127.0.0.1:60507/solr
[junit4:junit4]   2> 
[junit4:junit4]   2> 16095 T928 C95 P60507 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=2 status=0 QTime=1 
[junit4:junit4]   2>  live:true
[junit4:junit4]   2>  num:2
[junit4:junit4]   2> 
[junit4:junit4]   2> ######shard1 is not consistent.  Got 4 from org.apache.solr.client.solrj.impl.HttpSolrServer@8fb9f2lastClient and got 2 from http://127.0.0.1:60507/solr/collection1
[junit4:junit4]   2> 16097 T916 C98 P40379 REQ /select {fl=id,_version_&sort=id+asc&q=*:*&distrib=false&wt=javabin&rows=1000&version=2} hits=4 status=0 QTime=1 
[junit4:junit4]   2> 16098 T928 C95 P60507 REQ /select {fl=id,_version_&sort=id+asc&q=*:*&distrib=false&wt=javabin&rows=1000&version=2} hits=2 status=0 QTime=0 
[junit4:junit4]   2> ######org.apache.solr.client.solrj.impl.HttpSolrServer@8fb9f2: {numFound=4,start=0,docs=[SolrDocument{id=0, _version_=1409416963687448576}, SolrDocument{id=1, _version_=1409416963693740032}, SolrDocument{id=2, _version_=1409416963700031488}, SolrDocument{id=3, _version_=1409416963706322944}]}
[junit4:junit4]   2> ######org.apache.solr.client.solrj.impl.HttpSolrServer@786e0d: {numFound=2,start=0,docs=[SolrDocument{id=0, _version_=1409416963687448576}, SolrDocument{id=1, _version_=1409416963693740032}]}
[junit4:junit4]   2> ###### sizes=4,2
[junit4:junit4]   2> ###### Only in org.apache.solr.client.solrj.impl.HttpSolrServer@8fb9f2: [{id=2, _version_=1409416963700031488}, {id=3, _version_=1409416963706322944}]
[junit4:junit4]   2> client2
[junit4:junit4]   2> PROPS:shard=shard1
[junit4:junit4]   2> roles=null
[junit4:junit4]   2> state=active
[junit4:junit4]   2> core=collection1
[junit4:junit4]   2> collection=collection1
[junit4:junit4]   2> node_name=127.0.0.1:47590_solr
[junit4:junit4]   2> base_url=http://127.0.0.1:47590/solr
[junit4:junit4]   2> 
[junit4:junit4]   2> 16100 T941 C96 P47590 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=3 status=0 QTime=0 
[junit4:junit4]   2>  live:true
[junit4:junit4]   2>  num:3
[junit4:junit4]   2> 
[junit4:junit4]   2> client3
[junit4:junit4]   2> PROPS:shard=shard1
[junit4:junit4]   2> roles=null
[junit4:junit4]   2> state=active
[junit4:junit4]   2> core=collection1
[junit4:junit4]   2> collection=collection1
[junit4:junit4]   2> node_name=127.0.0.1:48775_solr
[junit4:junit4]   2> base_url=http://127.0.0.1:48775/solr
[junit4:junit4]   2> 
[junit4:junit4]   2> 16102 T954 C97 P48775 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   2>  live:true
[junit4:junit4]   2>  num:4
[junit4:junit4]   2> 
[junit4:junit4]   2> 16103 T889 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16105 T942 oasha.CollectionsHandler.handleSyncShardAction Syncing shard : action=SYNCSHARD&collection=collection1&shard=shard1&wt=javabin&version=2
[junit4:junit4]   2> 16105 T942 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16107 T921 oasha.CoreAdminHandler.handleRequestSyncAction I have been requested to sync up my shard
[junit4:junit4]   2> 16107 T921 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:40379/solr/collection1/
[junit4:junit4]   2> 16107 T921 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:40379/solr START replicas=[http://127.0.0.1:60507/solr/collection1/, http://127.0.0.1:47590/solr/collection1/, http://127.0.0.1:48775/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 16108 T929 C95 P60507 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 16108 T955 C97 P48775 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 16108 T943 C96 P47590 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 16109 T921 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:40379/solr  Received 3 versions from 127.0.0.1:60507/solr/collection1/
[junit4:junit4]   2> 16109 T921 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:40379/solr  Our versions are newer. ourLowThreshold=1409416963680108544 otherHigh=1409416963693740032
[junit4:junit4]   2> 16109 T921 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:40379/solr  Received 4 versions from 127.0.0.1:47590/solr/collection1/
[junit4:junit4]   2> 16109 T921 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:40379/solr  Our versions are newer. ourLowThreshold=1409416963680108544 otherHigh=1409416963700031488
[junit4:junit4]   2> 16109 T921 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:40379/solr  Received 5 versions from 127.0.0.1:48775/solr/collection1/
[junit4:junit4]   2> 16110 T921 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:40379/solr  Our versions are newer. ourLowThreshold=1409416963680108544 otherHigh=1409416963700031488
[junit4:junit4]   2> 16110 T921 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:40379/solr DONE. sync succeeded
[junit4:junit4]   2> 16110 T921 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 16110 T921 oasc.SyncStrategy.syncToMe http://127.0.0.1:40379/solr/collection1/: try and ask http://127.0.0.1:60507/solr/collection1/ to sync
[junit4:junit4]   2> 16110 T921 oasc.SyncStrategy.syncToMe http://127.0.0.1:40379/solr/collection1/: try and ask http://127.0.0.1:47590/solr/collection1/ to sync
[junit4:junit4]   2> 16110 T921 oasc.SyncStrategy.syncToMe http://127.0.0.1:40379/solr/collection1/: try and ask http://127.0.0.1:48775/solr/collection1/ to sync
[junit4:junit4]   2> 16111 T930 C95 P60507 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:60507/solr START replicas=[http://127.0.0.1:40379/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 16111 T944 C96 P47590 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:47590/solr START replicas=[http://127.0.0.1:40379/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 16112 T956 C97 P48775 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:48775/solr START replicas=[http://127.0.0.1:40379/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 16112 T917 C98 P40379 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 16112 T944 C96 P47590 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:47590/solr  Received 5 versions from 127.0.0.1:40379/solr/collection1/
[junit4:junit4]   2> 16112 T915 C98 P40379 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 16113 T944 C96 P47590 oasu.PeerSync.requestUpdates PeerSync: core=collection1 url=http://127.0.0.1:47590/solr Requesting updates from 127.0.0.1:40379/solr/collection1/n=1 versions=[1409416963706322944]
[junit4:junit4]   2> 16113 T918 C98 P40379 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 16113 T956 C97 P48775 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:48775/solr  Received 5 versions from 127.0.0.1:40379/solr/collection1/
[junit4:junit4]   2> 16113 T956 C97 P48775 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:48775/solr  Our versions are newer. ourLowThreshold=1409416963680108544 otherHigh=1409416963700031488
[junit4:junit4]   2> 16113 T930 C95 P60507 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:60507/solr  Received 5 versions from 127.0.0.1:40379/solr/collection1/
[junit4:junit4]   2> 16113 T956 C97 P48775 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:48775/solr DONE. sync succeeded
[junit4:junit4]   2> 16113 T930 C95 P60507 oasu.PeerSync.requestUpdates PeerSync: core=collection1 url=http://127.0.0.1:60507/solr Requesting updates from 127.0.0.1:40379/solr/collection1/n=2 versions=[1409416963706322944, 1409416963700031488]
[junit4:junit4]   2> 16113 T956 C97 P48775 REQ /get {sync=http://127.0.0.1:40379/solr/collection1/&getVersions=100&distrib=false&qt=/get&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16114 T921 oasc.SyncStrategy.syncToMe http://127.0.0.1:40379/solr/collection1/:  sync completed with http://127.0.0.1:48775/solr/collection1/
[junit4:junit4]   2> 16114 T915 C98 P40379 REQ /get {distrib=false&getUpdates=1409416963706322944&qt=/get&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 16115 T918 C98 P40379 REQ /get {distrib=false&getUpdates=1409416963706322944,1409416963700031488&qt=/get&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 16116 T944 C96 P47590 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:47590/solr DONE. sync succeeded
[junit4:junit4]   2> 16116 T944 C96 P47590 REQ /get {sync=http://127.0.0.1:40379/solr/collection1/&getVersions=100&distrib=false&qt=/get&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 16117 T930 C95 P60507 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:60507/solr DONE. sync succeeded
[junit4:junit4]   2> 16117 T921 oasc.SyncStrategy.syncToMe http://127.0.0.1:40379/solr/collection1/:  sync completed with http://127.0.0.1:47590/solr/collection1/
[junit4:junit4]   2> 16117 T930 C95 P60507 REQ /get {sync=http://127.0.0.1:40379/solr/collection1/&getVersions=100&distrib=false&qt=/get&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 16117 T921 oasc.SyncStrategy.syncToMe http://127.0.0.1:40379/solr/collection1/:  sync completed with http://127.0.0.1:60507/solr/collection1/
[junit4:junit4]   2> 16118 T889 oasc.AbstractDistributedZkTestCase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):360
[junit4:junit4]   2> 16118 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16120 T901 C99 P34700 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16120 T901 C99 P34700 oass.SolrIndexSearcher.<init> Opening Searcher@1b0dae3 main
[junit4:junit4]   2> 16121 T901 C99 P34700 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16121 T912 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b0dae3 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C4)}
[junit4:junit4]   2> 16121 T901 C99 P34700 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 1
[junit4:junit4]   2> 16122 T919 C98 P40379 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16123 T919 C98 P40379 oass.SolrIndexSearcher.<init> Opening Searcher@da11c7 main
[junit4:junit4]   2> 16123 T919 C98 P40379 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16123 T924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@da11c7 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C4)}
[junit4:junit4]   2> 16125 T927 C95 P60507 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16125 T940 C96 P47590 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16125 T953 C97 P48775 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16125 T953 C97 P48775 oass.SolrIndexSearcher.<init> Opening Searcher@199acba main
[junit4:junit4]   2> 16126 T953 C97 P48775 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16126 T962 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@199acba main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C4)}
[junit4:junit4]   2> 16126 T953 C97 P48775 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 1
[junit4:junit4]   2> 16150 T927 C95 P60507 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index,segFN=segments_3,generation=3,filenames=[_0_Asserting_0.tim, _0_MockRandom_0.tib, _0.fnm, _0_Asserting_0.tip, _0_MockRandom_0.prx, _0_nrm.cfs, _0_Direct_0.frq, _0_Asserting_0.prx, _0_Asserting_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockRandom_0.tiv, _0_SimpleText_0.pst, _0_nrm.cfe, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0.si, _0_Direct_0.tim, _0.fdt, segments_3]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index,segFN=segments_4,generation=4,filenames=[_0_Asserting_0.tim, _0_Asserting_0.tip, _0_MockRandom_0.prx, _1.fnm, _0_Asserting_0.prx, _1_Asserting_0.tim, _1_Asserting_0.tip, _0_SimpleText_0.pst, _1_Direct_0.tim, _0.si, _1_Direct_0.tip, _0_MockRandom_0.tib, _1_nrm.cfs, _0.fnm, _1_MockRandom_0.tip, _1_Asserting_0.frq, _1_MockRandom_0.tim, _1_MockRandom_0.sd, _0_nrm.cfs, _0_Direct_0.frq, _1_Direct_0.prx, _1_nrm.cfe, _1.fdx, _1_MockRandom_0.prx, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_Asserting_0.frq, _0_MockRandom_0.tiv, _1.fdt, _1_Asserting_0.prx, _1.si, _0_nrm.cfe, _0_Direct_0.tip, _0.fdx, _0_Direct_0.prx, _1_MockRandom_0.frq, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq, _1_SimpleText_0.pst, segments_4]
[junit4:junit4]   2> 16151 T927 C95 P60507 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 16152 T927 C95 P60507 oass.SolrIndexSearcher.<init> Opening Searcher@1701b4e main
[junit4:junit4]   2> 16152 T927 C95 P60507 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16152 T936 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1701b4e main{StandardDirectoryReader(segments_4:6:nrt _0(5.0):C2 _1(5.0):C2)}
[junit4:junit4]   2> 16153 T927 C95 P60507 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 28
[junit4:junit4]   2> 16157 T940 C96 P47590 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index,segFN=segments_3,generation=3,filenames=[_0_Asserting_0.tim, _0_MockRandom_0.tib, _0.fnm, _0_Asserting_0.tip, _0_MockRandom_0.prx, _0_nrm.cfs, _0_Direct_0.frq, _0_Asserting_0.prx, _0_Asserting_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_SimpleText_0.pst, _0_nrm.cfe, _0_MockRandom_0.tii, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0.si, _0_Direct_0.tim, _0.fdt, segments_3]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index,segFN=segments_4,generation=4,filenames=[_0_Asserting_0.tim, _0_Asserting_0.tip, _0_MockRandom_0.prx, _1_MockRandom_0.pos, _1.fnm, _0_Asserting_0.prx, _1_Asserting_0.tim, _1_Asserting_0.tip, _0_SimpleText_0.pst, _1_Direct_0.tim, _0.si, _1_Direct_0.tip, _0_MockRandom_0.tib, _1_nrm.cfs, _1_MockRandom_0.tiv, _0.fnm, _1_Asserting_0.frq, _1_MockRandom_0.sd, _1_MockRandom_0.skp, _0_nrm.cfs, _0_Direct_0.frq, _1_Direct_0.prx, _1_nrm.cfe, _1.fdx, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_Asserting_0.frq, _1_MockRandom_0.pyl, _1_MockRandom_0.tib, _1.fdt, _1_Asserting_0.prx, _1.si, _0_nrm.cfe, _0_MockRandom_0.tii, _1_MockRandom_0.doc, _0_Direct_0.tip, _0.fdx, _0_Direct_0.prx, _1_MockRandom_0.frq, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq, _1_SimpleText_0.pst, segments_4]
[junit4:junit4]   2> 16157 T940 C96 P47590 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 16158 T940 C96 P47590 oass.SolrIndexSearcher.<init> Opening Searcher@9dea1a main
[junit4:junit4]   2> 16159 T940 C96 P47590 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16159 T949 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9dea1a main{StandardDirectoryReader(segments_4:6:nrt _0(5.0):C3 _1(5.0):C1)}
[junit4:junit4]   2> 16159 T940 C96 P47590 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2} {commit=} 0 34
[junit4:junit4]   2> 16159 T919 C98 P40379 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 37
[junit4:junit4]   2> 16160 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16162 T916 C98 P40379 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   2> 16164 T928 C95 P60507 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=4 status=0 QTime=1 
[junit4:junit4]   2> 16165 T941 C96 P47590 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   2> 16167 T954 C97 P48775 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   2> 18172 T902 C99 P34700 REQ /select {fl=id,score&shard.url=127.0.0.1:34700/solr/collection1/&NOW=1344124761562&start=0&q=*:*&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   2> 18174 T902 C99 P34700 REQ /select {shard.url=127.0.0.1:34700/solr/collection1/&NOW=1344124761562&q=*:*&ids=3,2,1,0&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 18174 T901 C99 P34700 REQ /select {wt=javabin&q=*:*&version=2} status=0 QTime=5 
[junit4:junit4]   2> Control Docs:4
[junit4:junit4]   2> 18175 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> check const of shard1
[junit4:junit4]   2> client0
[junit4:junit4]   2> PROPS:shard=shard1
[junit4:junit4]   2> leader=true
[junit4:junit4]   2> roles=null
[junit4:junit4]   2> state=active
[junit4:junit4]   2> core=collection1
[junit4:junit4]   2> collection=collection1
[junit4:junit4]   2> node_name=127.0.0.1:40379_solr
[junit4:junit4]   2> base_url=http://127.0.0.1:40379/solr
[junit4:junit4]   2> 
[junit4:junit4]   2> 18178 T916 C98 P40379 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=4 status=0 QTime=1 
[junit4:junit4]   2>  live:true
[junit4:junit4]   2>  num:4
[junit4:junit4]   2> 
[junit4:junit4]   2> client1
[junit4:junit4]   2> PROPS:shard=shard1
[junit4:junit4]   2> roles=null
[junit4:junit4]   2> state=active
[junit4:junit4]   2> core=collection1
[junit4:junit4]   2> collection=collection1
[junit4:junit4]   2> node_name=127.0.0.1:60507_solr
[junit4:junit4]   2> base_url=http://127.0.0.1:60507/solr
[junit4:junit4]   2> 
[junit4:junit4]   2> 18179 T928 C95 P60507 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   2>  live:true
[junit4:junit4]   2>  num:4
[junit4:junit4]   2> 
[junit4:junit4]   2> client2
[junit4:junit4]   2> PROPS:shard=shard1
[junit4:junit4]   2> roles=null
[junit4:junit4]   2> state=active
[junit4:junit4]   2> core=collection1
[junit4:junit4]   2> collection=collection1
[junit4:junit4]   2> node_name=127.0.0.1:47590_solr
[junit4:junit4]   2> base_url=http://127.0.0.1:47590/solr
[junit4:junit4]   2> 
[junit4:junit4]   2> 18181 T941 C96 P47590 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   2>  live:true
[junit4:junit4]   2>  num:4
[junit4:junit4]   2> 
[junit4:junit4]   2> client3
[junit4:junit4]   2> PROPS:shard=shard1
[junit4:junit4]   2> roles=null
[junit4:junit4]   2> state=active
[junit4:junit4]   2> core=collection1
[junit4:junit4]   2> collection=collection1
[junit4:junit4]   2> node_name=127.0.0.1:48775_solr
[junit4:junit4]   2> base_url=http://127.0.0.1:48775/solr
[junit4:junit4]   2> 
[junit4:junit4]   2> 18183 T954 C97 P48775 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   2>  live:true
[junit4:junit4]   2>  num:4
[junit4:junit4]   2> 
[junit4:junit4]   2> 18186 T985 C98 P40379 REQ /select {fl=id,score&shard.url=127.0.0.1:40379/solr/collection1/|127.0.0.1:60507/solr/collection1/|127.0.0.1:47590/solr/collection1/|127.0.0.1:48775/solr/collection1/&NOW=1344124761578&start=0&q=*:*&distrib=false&isShard=true&wt=javabin&fsv=true&rows=10&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   2> 18188 T985 C98 P40379 REQ /select {shard.url=127.0.0.1:40379/solr/collection1/|127.0.0.1:60507/solr/collection1/|127.0.0.1:47590/solr/collection1/|127.0.0.1:48775/solr/collection1/&NOW=1344124761578&q=*:*&ids=3,2,1,0&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 18189 T919 C98 P40379 REQ /select {wt=javabin&q=*:*&version=2} status=0 QTime=4 
[junit4:junit4]   2> 18191 T901 C99 P34700 /update {wt=javabin&version=2} {add=[4 (1409416966001655808)]} 0 1
[junit4:junit4]   2> 18195 T940 C96 P47590 REQ /update {update.distrib=FROMLEADER&test.distrib.skip.servers=http://127.0.0.1:48775/solr/collection1/&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 18195 T927 C95 P60507 REQ /update {update.distrib=FROMLEADER&test.distrib.skip.servers=http://127.0.0.1:48775/solr/collection1/&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 18196 T919 C98 P40379 /update {test.distrib.skip.servers=http://127.0.0.1:48775/solr/collection1/&wt=javabin&version=2} {add=[4 (1409416966004801536)]} 0 3
[junit4:junit4]   2> 18196 T889 oasc.ChaosMonkey.monkeyLog monkey: kill shard! 40379
[junit4:junit4]   2> 18196 T889 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=16130663
[junit4:junit4]   2> 18197 T889 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4148d4
[junit4:junit4]   2> 18198 T889 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 18198 T889 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=6,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 18217 T889 C98 P40379 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=3
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty1/index,segFN=segments_2,generation=2,filenames=[_0_Asserting_0.tim, _0.fnm, _0_Asserting_0.tip, _0_MockRandom_0.prx, _0_nrm.cfs, _0_Direct_0.frq, _0_Asserting_0.prx, _0_Asserting_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockRandom_0.tip, _0_SimpleText_0.pst, _0_nrm.cfe, segments_2, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0.si, _0_MockRandom_0.tim, _0_Direct_0.tim, _0.fdt]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty1/index,segFN=segments_3,generation=3,filenames=[_0_Asserting_0.tim, _0_Asserting_0.tip, _0_MockRandom_0.prx, _1.fnm, _0_Asserting_0.prx, _1_Asserting_0.tim, _1_Asserting_0.tip, _0_SimpleText_0.pst, _1_Direct_0.tim, _0.si, _1_Direct_0.tip, _1_nrm.cfs, _1_MockRandom_0.tiv, _0.fnm, _1_Asserting_0.frq, _1_MockRandom_0.sd, _0_nrm.cfs, _0_Direct_0.frq, _1_Direct_0.prx, _1_nrm.cfe, _1.fdx, _1_MockRandom_0.prx, _0_MockRandom_0.tip, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_Asserting_0.frq, _1_MockRandom_0.tib, _1.fdt, _1_Asserting_0.prx, _1.si, _0_nrm.cfe, _0_Direct_0.tip, _0.fdx, _0_Direct_0.prx, _1_MockRandom_0.frq, _0_Direct_0.tim, _0_MockRandom_0.tim, segments_3, _0.fdt, _1_Direct_0.frq, _1_SimpleText_0.pst]
[junit4:junit4]   2> 18217 T889 C98 P40379 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 18219 T894 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138f413eee60003
[junit4:junit4]   2> 18220 T909 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 18220 T965 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 18220 T948 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 18220 T923 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 18220 T970 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 18220 T935 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 18220 T961 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 18220 T891 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36768 which had sessionid 0x138f413eee60003
[junit4:junit4]   2> 18220 T889 oaz.ZooKeeper.close Session: 0x138f413eee60003 closed
[junit4:junit4]   2> 18221 T889 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 18222 T935 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60507/solr/collection1/
[junit4:junit4]   2> 18222 T935 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:60507/solr START replicas=[http://127.0.0.1:47590/solr/collection1/, http://127.0.0.1:48775/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 18224 T955 C97 P48775 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 18224 T943 C96 P47590 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 18224 T935 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:60507/solr  Received 5 versions from 127.0.0.1:48775/solr/collection1/
[junit4:junit4]   2> 18224 T935 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:60507/solr  Our versions are newer. ourLowThreshold=1409416963687448576 otherHigh=1409416963700031488
[junit4:junit4]   2> 18224 T935 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:60507/solr  Received 6 versions from 127.0.0.1:47590/solr/collection1/
[junit4:junit4]   2> 18225 T935 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:60507/solr  Our versions are newer. ourLowThreshold=1409416963687448576 otherHigh=1409416963706322944
[junit4:junit4]   2> 18225 T935 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:60507/solr DONE. sync succeeded
[junit4:junit4]   2> 18225 T935 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 18225 T935 oasc.SyncStrategy.syncToMe http://127.0.0.1:60507/solr/collection1/: try and ask http://127.0.0.1:47590/solr/collection1/ to sync
[junit4:junit4]   2> 18225 T935 oasc.SyncStrategy.syncToMe http://127.0.0.1:60507/solr/collection1/: try and ask http://127.0.0.1:48775/solr/collection1/ to sync
[junit4:junit4]   2> 18226 T944 C96 P47590 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:47590/solr START replicas=[http://127.0.0.1:60507/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 18226 T956 C97 P48775 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:48775/solr START replicas=[http://127.0.0.1:60507/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 18227 T931 C95 P60507 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 18228 T956 C97 P48775 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:48775/solr  Received 6 versions from 127.0.0.1:60507/solr/collection1/
[junit4:junit4]   2> 18228 T956 C97 P48775 oasu.PeerSync.requestUpdates PeerSync: core=collection1 url=http://127.0.0.1:48775/solr Requesting updates from 127.0.0.1:60507/solr/collection1/n=1 versions=[1409416966004801536]
[junit4:junit4]   2> 18228 T929 C95 P60507 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 18228 T944 C96 P47590 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:47590/solr  Received 6 versions from 127.0.0.1:60507/solr/collection1/
[junit4:junit4]   2> 18228 T944 C96 P47590 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:47590/solr  Our versions are newer. ourLowThreshold=1409416963687448576 otherHigh=1409416963706322944
[junit4:junit4]   2> 18229 T944 C96 P47590 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:47590/solr DONE. sync succeeded
[junit4:junit4]   2> 18229 T944 C96 P47590 REQ /get {sync=http://127.0.0.1:60507/solr/collection1/&getVersions=100&distrib=false&qt=/get&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 18229 T935 oasc.SyncStrategy.syncToMe http://127.0.0.1:60507/solr/collection1/:  sync completed with http://127.0.0.1:47590/solr/collection1/
[junit4:junit4]   2> 18229 T931 C95 P60507 REQ /get {distrib=false&getUpdates=1409416966004801536&qt=/get&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 18231 T956 C97 P48775 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:48775/solr DONE. sync succeeded
[junit4:junit4]   2> 18231 T956 C97 P48775 REQ /get {sync=http://127.0.0.1:60507/solr/collection1/&getVersions=100&distrib=false&qt=/get&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 18232 T935 oasc.SyncStrategy.syncToMe http://127.0.0.1:60507/solr/collection1/:  sync completed with http://127.0.0.1:48775/solr/collection1/
[junit4:junit4]   2> 18232 T935 oasc.RecoveryStrategy.close WARNING Stopping recovery for core collection1 zkNodeName=127.0.0.1:60507_solr_collection1
[junit4:junit4]   2> 18234 T935 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 18236 T894 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138f413eee60004 type:create cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 18272 T889 oasc.AbstractDistributedZkTestCase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):360
[junit4:junit4]   2> 18273 T889 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18275 T901 C99 P34700 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 18297 T901 C99 P34700 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/control/data/index,segFN=segments_2,generation=2,filenames=[_0_Asserting_0.tim, _0.fnm, _0_Asserting_0.tip, _0_MockRandom_0.prx, _0_nrm.cfs, _0_Direct_0.frq, _0_Asserting_0.prx, _0_Asserting_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockRandom_0.tip, _0_SimpleText_0.pst, _0_nrm.cfe, segments_2, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0.si, _0_MockRandom_0.tim, _0_Direct_0.tim, _0.fdt]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/control/data/index,segFN=segments_3,generation=3,filenames=[_0_Asserting_0.tim, _0_Asserting_0.tip, _0_MockRandom_0.prx, _1.fnm, _0_Asserting_0.prx, _1_Asserting_0.tim, _1_Asserting_0.tip, _0_SimpleText_0.pst, _1_Direct_0.tim, _0.si, _1_Direct_0.tip, _1_nrm.cfs, _1_MockRandom_0.tiv, _0.fnm, _1_Asserting_0.frq, _1_MockRandom_0.sd, _0_nrm.cfs, _0_Direct_0.frq, _1_Direct_0.prx, _1_nrm.cfe, _1.fdx, _1_MockRandom_0.prx, _0_MockRandom_0.tip, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_Asserting_0.frq, _1_MockRandom_0.tib, _1.fdt, _1_Asserting_0.prx, _1.si, _0_nrm.cfe, _0_Direct_0.tip, _0.fdx, _0_Direct_0.prx, _1_MockRandom_0.frq, _0_Direct_0.tim, _0_MockRandom_0.tim, segments_3, _0.fdt, _1_Direct_0.frq, _1_SimpleText_0.pst]
[junit4:junit4]   2> 18297 T901 C99 P34700 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 18299 T901 C99 P34700 oass.SolrIndexSearcher.<init> Opening Searcher@b8899a main
[junit4:junit4]   2> 18299 T901 C99 P34700 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 18299 T912 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b8899a main{StandardDirectoryReader(segments_3:5:nrt _0(5.0):C4 _1(5.0):C1)}
[junit4:junit4]   2> 18299 T901 C99 P34700 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 24
[junit4:junit4]   2> 18300 T894 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138f413eee60008
[junit4:junit4]   2> 18301 T970 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 18301 T891 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36797 which had sessionid 0x138f413eee60008
[junit4:junit4]   2> 18301 T889 oaz.ZooKeeper.close Session: 0x138f413eee60008 closed
[junit4:junit4]   2> 18302 T894 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138f413eee60007
[junit4:junit4]   2> 18302 T965 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 18302 T891 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36778 which had sessionid 0x138f413eee60007
[junit4:junit4]   2> 18302 T889 oaz.ZooKeeper.close Session: 0x138f413eee60007 closed
[junit4:junit4]   2> 18303 T894 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4:junit4]   2> 18303 T893 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4]   2> 18303 T889 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 18305 T889 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36773 which had sessionid 0x138f413eee60005
[junit4:junit4]   2> 18305 T947 oaz.ClientCnxn$SendThread.run Unable to read additional data from server sessionid 0x138f413eee60005, likely server has closed socket, closing socket connection and attempting reconnect
[junit4:junit4]   2> 18305 T889 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36766 which had sessionid 0x138f413eee60002
[junit4:junit4]   2> 18305 T908 oaz.ClientCnxn$SendThread.run Unable to read additional data from server sessionid 0x138f413eee60002, likely server has closed socket, closing socket connection and attempting reconnect
[junit4:junit4]   2> 18305 T889 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36770 which had sessionid 0x138f413eee60004
[junit4:junit4]   2> 18305 T934 oaz.ClientCnxn$SendThread.run Unable to read additional data from server sessionid 0x138f413eee60004, likely server has closed socket, closing socket connection and attempting reconnect
[junit4:junit4]   2> 18305 T889 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36775 which had sessionid 0x138f413eee60006
[junit4:junit4]   2> 18306 T960 oaz.ClientCnxn$SendThread.run Unable to read additional data from server sessionid 0x138f413eee60006, likely server has closed socket, closing socket connection and attempting reconnect
[junit4:junit4]   2> 18306 T891 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4:junit4]   2> 18306 T889 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 18307 T889 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 34700
[junit4:junit4]   2> 18307 T889 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=30241313
[junit4:junit4]   2> 18307 T889 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@19cb8
[junit4:junit4]   2> 18308 T889 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 18308 T889 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 18405 T948 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1980b94 name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 18405 T909 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9d4f7b name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 18405 T889 oaz.ZooKeeper.close Session: 0x138f413eee60002 closed
[junit4:junit4]   2> 18406 T909 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 18405 T935 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5abdb2 name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 18406 T961 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@48ec58 name:ZooKeeperConnection Watcher:127.0.0.1:36284/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 18406 T911 oasc.OverseerCollectionProcessor.run WARNING Overseer cannot talk to ZK
[junit4:junit4]   2> 18406 T889 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 18459 T889 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 40379
[junit4:junit4]   2> 18459 T889 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 60507
[junit4:junit4]   2> 18459 T889 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=18916081
[junit4:junit4]   2> 18459 T889 oasc.RecoveryStrategy.close WARNING Stopping recovery for core collection1 zkNodeName=127.0.0.1:60507_solr_collection1
[junit4:junit4]   2> 18459 T889 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1ec34e0
[junit4:junit4]   2> 18460 T889 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 18460 T889 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 18477 T889 C95 P60507 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index,segFN=segments_4,generation=4,filenames=[_0_Asserting_0.tim, _0_Asserting_0.tip, _0_MockRandom_0.prx, _1.fnm, _0_Asserting_0.prx, _1_Asserting_0.tim, _1_Asserting_0.tip, _0_SimpleText_0.pst, _1_Direct_0.tim, _0.si, _1_Direct_0.tip, _0_MockRandom_0.tib, _1_nrm.cfs, _0.fnm, _1_MockRandom_0.tip, _1_Asserting_0.frq, _1_MockRandom_0.tim, _1_MockRandom_0.sd, _0_nrm.cfs, _0_Direct_0.frq, _1_Direct_0.prx, _1_nrm.cfe, _1.fdx, _1_MockRandom_0.prx, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_Asserting_0.frq, _0_MockRandom_0.tiv, _1.fdt, _1_Asserting_0.prx, _1.si, _0_nrm.cfe, _0_Direct_0.tip, _0.fdx, _0_Direct_0.prx, _1_MockRandom_0.frq, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq, _1_SimpleText_0.pst, segments_4]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty2/index,segFN=segments_5,generation=5,filenames=[_2_Direct_0.tip, _2_Direct_0.tim, _2_SimpleText_0.pst, _0_MockRandom_0.prx, _1.fnm, _0_Asserting_0.prx, _1_Asserting_0.tim, _1_Asserting_0.tip, _0_SimpleText_0.pst, _2_Asserting_0.prx, _1_Direct_0.tim, _1_Direct_0.tip, _0.fnm, _1_MockRandom_0.tip, _2.fdt, _1_MockRandom_0.sd, _1_MockRandom_0.tim, _0_Direct_0.frq, _2.fdx, _2_Asserting_0.tim, _1.fdx, _0_Asserting_0.frq, _0_MockRandom_0.frq, _2_Asserting_0.tip, _1.fdt, _1_MockRandom_0.frq, _1_SimpleText_0.pst, _0_Asserting_0.tim, _2.si, _2_MockRandom_0.tii, _0_Asserting_0.tip, _2_nrm.cfe, _2_Asserting_0.frq, _2_MockRandom_0.tib, _2_nrm.cfs, _0.si, _2_MockRandom_0.prx, _1_nrm.cfs, _0_MockRandom_0.tib, _1_Asserting_0.frq, _0_nrm.cfs, _1_Direct_0.prx, _2.fnm, _1_nrm.cfe, _2_Direct_0.prx, _2_MockRandom_0.sd, _0_MockRandom_0.sd, _1_MockRandom_0.prx, _0_MockRandom_0.tiv, _1_Asserting_0.prx, _1.si, _0_nrm.cfe, _2_MockRandom_0.frq, _2_Direct_0.frq, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, segments_5, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq]
[junit4:junit4]   2> 18477 T889 C95 P60507 oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4]   2> 18478 T889 oasc.RecoveryStrategy.close WARNING Stopping recovery for core collection1 zkNodeName=127.0.0.1:60507_solr_collection1
[junit4:junit4]   2> 19220 T923 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 19220 T923 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 19375 T910 oasc.Overseer$ClusterStateUpdater.amILeader WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[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.getData(ZooKeeper.java:927)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer$ClusterStateUpdater.amILeader(Overseer.java:187)
[junit4:junit4]   2> 	at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:113)
[junit4:junit4]   2> 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   2> 
[junit4:junit4]   2> 19375 T910 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=88088559183331330-127.0.0.1:34700_solr-n_0000000000) am no longer a leader.
[junit4:junit4]   2> 19576 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36284
[junit4:junit4]   2> 19577 T960 oaz.ClientCnxn$SendThread.run WARNING Session 0x138f413eee60006 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused
[junit4:junit4]   2> 	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4:junit4]   2> 	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:599)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1143)
[junit4:junit4]   2> 
[junit4:junit4]   2> 20160 T934 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36284
[junit4:junit4]   2> 20260 T935 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20260 T889 oaz.ZooKeeper.close Session: 0x138f413eee60004 closed
[junit4:junit4]   2> 20261 T889 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 20303 T947 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36284
[junit4:junit4]   2> 20304 T947 oaz.ClientCnxn$SendThread.run WARNING Session 0x138f413eee60005 for server null, unexpected error, closing socket connection and attempting reconnect java.net.ConnectException: Connection refused
[junit4:junit4]   2> 	at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4:junit4]   2> 	at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:599)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1143)
[junit4:junit4]   2> 
[junit4:junit4]   2> 20314 T889 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 47590
[junit4:junit4]   2> 20314 T889 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=3580484
[junit4:junit4]   2> 20314 T889 oasc.RecoveryStrategy.close WARNING Stopping recovery for core collection1 zkNodeName=127.0.0.1:47590_solr_collection1
[junit4:junit4]   2> 20314 T889 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1655fe4
[junit4:junit4]   2> 20315 T889 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 20315 T889 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 20337 T889 C96 P47590 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index,segFN=segments_4,generation=4,filenames=[_0_Asserting_0.tim, _0_Asserting_0.tip, _0_MockRandom_0.prx, _1_MockRandom_0.pos, _1.fnm, _0_Asserting_0.prx, _1_Asserting_0.tim, _1_Asserting_0.tip, _0_SimpleText_0.pst, _1_Direct_0.tim, _0.si, _1_Direct_0.tip, _0_MockRandom_0.tib, _1_nrm.cfs, _1_MockRandom_0.tiv, _0.fnm, _1_Asserting_0.frq, _1_MockRandom_0.sd, _1_MockRandom_0.skp, _0_nrm.cfs, _0_Direct_0.frq, _1_Direct_0.prx, _1_nrm.cfe, _1.fdx, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_Asserting_0.frq, _1_MockRandom_0.pyl, _1_MockRandom_0.tib, _1.fdt, _1_Asserting_0.prx, _1.si, _0_nrm.cfe, _0_MockRandom_0.tii, _1_MockRandom_0.doc, _0_Direct_0.tip, _0.fdx, _0_Direct_0.prx, _1_MockRandom_0.frq, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq, _1_SimpleText_0.pst, segments_4]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty3/index,segFN=segments_5,generation=5,filenames=[_2_Direct_0.tip, _2_Direct_0.tim, _2_SimpleText_0.pst, _0_MockRandom_0.prx, _1.fnm, _1_MockRandom_0.pos, _0_Asserting_0.prx, _1_Asserting_0.tim, _1_Asserting_0.tip, _0_SimpleText_0.pst, _2_Asserting_0.prx, _1_Direct_0.tim, _1_Direct_0.tip, _1_MockRandom_0.tiv, _0.fnm, _2.fdt, _1_MockRandom_0.sd, _0_Direct_0.frq, _2.fdx, _2_Asserting_0.tim, _1.fdx, _0_Asserting_0.frq, _0_MockRandom_0.frq, _2_Asserting_0.tip, _1_MockRandom_0.tib, _1.fdt, _1_MockRandom_0.doc, _1_MockRandom_0.frq, _1_SimpleText_0.pst, _0_Asserting_0.tim, _2.si, _2_MockRandom_0.tii, _0_Asserting_0.tip, _2_nrm.cfe, _2_MockRandom_0.pyl, _2_Asserting_0.frq, _2_MockRandom_0.pos, _2_MockRandom_0.tib, _2_nrm.cfs, _0.si, _1_nrm.cfs, _0_MockRandom_0.tib, _1_Asserting_0.frq, _0_nrm.cfs, _1_MockRandom_0.skp, _1_Direct_0.prx, _2.fnm, _1_nrm.cfe, _2_MockRandom_0.sd, _2_Direct_0.prx, _0_MockRandom_0.sd, _1_MockRandom_0.pyl, _2_MockRandom_0.skp, _1_Asserting_0.prx, _1.si, _0_nrm.cfe, _2_MockRandom_0.frq, _0_MockRandom_0.tii, _2_Direct_0.frq, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, segments_5, _2_MockRandom_0.doc, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq]
[junit4:junit4]   2> 20338 T889 C96 P47590 oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4]   2> 20338 T889 oasc.RecoveryStrategy.close WARNING Stopping recovery for core collection1 zkNodeName=127.0.0.1:47590_solr_collection1
[junit4:junit4]   2> 20404 T948 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20404 T889 oaz.ZooKeeper.close Session: 0x138f413eee60005 closed
[junit4:junit4]   2> 20405 T889 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 20456 T889 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 48775
[junit4:junit4]   2> 20457 T889 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=32935721
[junit4:junit4]   2> 20457 T889 oasc.RecoveryStrategy.close WARNING Stopping recovery for core collection1 zkNodeName=127.0.0.1:48775_solr_collection1
[junit4:junit4]   2> 20457 T889 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1db5c17
[junit4:junit4]   2> 20458 T889 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 20458 T889 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 20477 T889 C97 P48775 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty4/index,segFN=segments_3,generation=3,filenames=[_0_Asserting_0.tim, _0_MockRandom_0.tib, _0.fnm, _0_Asserting_0.tip, _0_nrm.cfs, _0_Direct_0.frq, _0_MockRandom_0.skp, _0_Asserting_0.prx, _0_Asserting_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.frq, _0_MockRandom_0.tiv, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _0_SimpleText_0.pst, _0_nrm.cfe, _0.fdx, _0_Direct_0.tip, _0_Direct_0.prx, _0.si, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0.fdt, segments_3]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-1344124743396/jetty4/index,segFN=segments_4,generation=4,filenames=[_0_Asserting_0.tim, _0_Asserting_0.tip, _1.fnm, _0_Asserting_0.prx, _1_Asserting_0.tim, _1_Asserting_0.tip, _0_SimpleText_0.pst, _1_Direct_0.tim, _0.si, _1_Direct_0.tip, _0_MockRandom_0.tib, _1_nrm.cfs, _0.fnm, _1_Asserting_0.frq, _1_MockRandom_0.sd, _0_nrm.cfs, _0_Direct_0.frq, _1_Direct_0.prx, _1_MockRandom_0.tii, _0_MockRandom_0.skp, _1_nrm.cfe, _1.fdx, _1_MockRandom_0.prx, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_Asserting_0.frq, _1_MockRandom_0.tib, _0_MockRandom_0.tiv, _1.fdt, _1_Asserting_0.prx, _0_MockRandom_0.doc, _1.si, _0_nrm.cfe, _0_MockRandom_0.pos, _0_Direct_0.tip, _0.fdx, _0_Direct_0.prx, _1_MockRandom_0.frq, _0_MockRandom_0.pyl, _0_Direct_0.tim, _0.fdt, _1_Direct_0.frq, _1_SimpleText_0.pst, segments_4]
[junit4:junit4]   2> 20477 T889 C97 P48775 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 20478 T889 oasc.RecoveryStrategy.close WARNING Stopping recovery for core collection1 zkNodeName=127.0.0.1:48775_solr_collection1
[junit4:junit4]   2> 20608 T892 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> 20897 T960 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:36284
[junit4:junit4]   2> 20998 T961 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 20998 T889 oaz.ZooKeeper.close Session: 0x138f413eee60006 closed
[junit4:junit4]   2> 20999 T889 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 21060 T889 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SyncSliceTest -Dtests.method=testDistribSearch -Dtests.seed=3B5E138D934449EE -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_YE -Dtests.timezone=Canada/Atlantic -Dtests.file.encoding=US-ASCII
[junit4:junit4]   2>
[junit4:junit4]    > (@AfterClass output)
[junit4:junit4]   2> 21547 T889 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {range_facet_l=PostingsFormat(name=MockRandom), text=PostingsFormat(name=SimpleText), _version_=PostingsFormat(name=Asserting), rnd_b=PostingsFormat(name=Direct), intDefault=PostingsFormat(name=Asserting), id=PostingsFormat(name=Direct), timestamp=PostingsFormat(name=MockRandom), a_t=PostingsFormat(name=MockRandom), range_facet_sl=PostingsFormat(name=Direct), range_facet_si=PostingsFormat(name=MockRandom), other_tl1=PostingsFormat(name=Asserting), multiDefault=PostingsFormat(name=MockRandom), a_si=PostingsFormat(name=Asserting)}, sim=RandomSimilarityProvider(queryNorm=true,coord=false): {}, locale=ar_YE, timezone=Canada/Atlantic
[junit4:junit4]   2> NOTE: Linux 3.2.0-27-generic i386/Sun Microsystems Inc. 1.6.0_33 (32-bit)/cpus=8,threads=7,free=141190832,total=287965184
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestSearchPerf, RecoveryZkTest, TestArbitraryIndexDir, SpellingQueryConverterTest, NumericFieldsTest, SpellPossibilityIteratorTest, QueryParsingTest, SoftAutoCommitTest, TestFoldingMultitermQuery, CacheHeaderTest, QueryElevationComponentTest, LukeRequestHandlerTest, PathHierarchyTokenizerFactoryTest, SolrCmdDistributorTest, SolrCoreTest, BasicDistributedZkTest, MoreLikeThisHandlerTest, TestFiltering, TestPHPSerializedResponseWriter, TestPropInject, ScriptEngineTest, MinimalSchemaTest, XmlUpdateRequestHandlerTest, DisMaxRequestHandlerTest, TestValueSourceCache, OutputWriterTest, SyncSliceTest]
[junit4:junit4]   2> 
[junit4:junit4] Completed on J0 in 21.56s, 1 test, 1 error <<< FAILURES!

[...truncated 427 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/build.xml:29: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/solr/build.xml:144: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/lucene/module-build.xml:62: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/lucene/common-build.xml:1095: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/checkout/lucene/common-build.xml:790: There were test failures: 223 suites, 899 tests, 1 error, 5 ignored

Total time: 19 minutes 34 seconds
Build step 'Execute shell' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message