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.7.0_07) - Build # 2013 - Still Failing!
Date Fri, 26 Oct 2012 19:25:48 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/2013/
Java: 32bit/jdk1.7.0_07 -server -XX:+UseSerialGC

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

Error Message:
Shard still reported as live in zk - 0 jetty

Stack Trace:
java.lang.AssertionError: Shard still reported as live in zk - 0 jetty
	at __randomizedtesting.SeedInfo.seed([9D0D1396FE5AEED2:1CEB9D8E89058EEE]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(AbstractFullDistribZkTestBase.java:1231)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(AbstractFullDistribZkTestBase.java:1216)
	at org.apache.solr.cloud.SyncSliceTest.waitToSeeDownInClusterState(SyncSliceTest.java:259)
	at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:223)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 9125 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.SyncSliceTest
[junit4:junit4]   2> 3 T2497 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SyncSliceTest-1351279506289
[junit4:junit4]   2> 4 T2497 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T2498 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 5 T2498 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 5 T2498 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 104 T2497 oasc.ZkTestServer.run start zk server on port:51870
[junit4:junit4]   2> 105 T2497 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51870 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@14e392b
[junit4:junit4]   2> 106 T2503 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51870
[junit4:junit4]   2> 106 T2497 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T2503 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51870, initiating session
[junit4:junit4]   2> 106 T2499 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:57818
[junit4:junit4]   2> 106 T2499 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:57818
[junit4:junit4]   2> 107 T2501 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 109 T2501 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9e88f3730000 with negotiated timeout 10000 for client /127.0.0.1:57818
[junit4:junit4]   2> 109 T2503 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:51870, sessionid = 0x13a9e88f3730000, negotiated timeout = 10000
[junit4:junit4]   2> 109 T2504 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14e392b name:ZooKeeperConnection Watcher:127.0.0.1:51870 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 109 T2497 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 109 T2497 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 111 T2502 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a9e88f3730000
[junit4:junit4]   2> 112 T2504 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 112 T2497 oaz.ZooKeeper.close Session: 0x13a9e88f3730000 closed
[junit4:junit4]   2> 112 T2499 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57818 which had sessionid 0x13a9e88f3730000
[junit4:junit4]   2> 112 T2497 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51870/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@25cefd
[junit4:junit4]   2> 113 T2505 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51870
[junit4:junit4]   2> 113 T2497 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 113 T2505 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51870, initiating session
[junit4:junit4]   2> 114 T2499 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:57819
[junit4:junit4]   2> 114 T2499 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:57819
[junit4:junit4]   2> 115 T2501 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9e88f3730001 with negotiated timeout 10000 for client /127.0.0.1:57819
[junit4:junit4]   2> 115 T2505 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:51870, sessionid = 0x13a9e88f3730001, negotiated timeout = 10000
[junit4:junit4]   2> 115 T2506 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@25cefd name:ZooKeeperConnection Watcher:127.0.0.1:51870/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 115 T2497 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 115 T2497 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 118 T2497 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 119 T2497 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 120 T2497 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 121 T2497 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 122 T2497 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 124 T2497 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 125 T2497 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 127 T2497 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 128 T2497 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 130 T2497 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 130 T2497 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 132 T2497 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 132 T2497 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 134 T2497 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 134 T2497 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 136 T2497 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 136 T2497 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 138 T2497 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 138 T2497 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 140 T2497 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 140 T2497 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 141 T2497 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 142 T2497 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 143 T2502 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a9e88f3730001
[junit4:junit4]   2> 143 T2506 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 143 T2497 oaz.ZooKeeper.close Session: 0x13a9e88f3730001 closed
[junit4:junit4]   2> 143 T2499 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:57819 which had sessionid 0x13a9e88f3730001
[junit4:junit4]   2> 192 T2497 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 194 T2497 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:33446
[junit4:junit4]   2> 195 T2497 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 195 T2497 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351279506430
[junit4:junit4]   2> 195 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351279506430/'
[junit4:junit4]   2> 206 T2497 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 207 T2497 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 207 T2497 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351279506430
[junit4:junit4]   2> 208 T2497 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351279506430/solr.xml
[junit4:junit4]   2> 208 T2497 oasc.CoreContainer.<init> New CoreContainer 31668292
[junit4:junit4]   2> 208 T2497 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351279506430/'
[junit4:junit4]   2> 208 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351279506430/'
[junit4:junit4]   2> 220 T2497 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 227 T2497 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51870/solr
[junit4:junit4]   2> 227 T2497 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51870/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@169167e
[junit4:junit4]   2> 228 T2516 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51870
[junit4:junit4]   2> 228 T2497 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 228 T2516 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51870, initiating session
[junit4:junit4]   2> 229 T2499 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:57820
[junit4:junit4]   2> 229 T2499 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:57820
[junit4:junit4]   2> 241 T2501 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9e88f3730002 with negotiated timeout 5000 for client /127.0.0.1:57820
[junit4:junit4]   2> 241 T2516 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:51870, sessionid = 0x13a9e88f3730002, negotiated timeout = 5000
[junit4:junit4]   2> 241 T2517 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@169167e name:ZooKeeperConnection Watcher:127.0.0.1:51870/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 242 T2497 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 243 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 244 T2497 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 245 T2497 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33446_solr
[junit4:junit4]   2> 245 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:33446_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:33446_solr
[junit4:junit4]   2> 246 T2497 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:33446_solr
[junit4:junit4]   2> 247 T2497 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 247 T2497 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 248 T2497 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 248 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 248 T2497 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 248 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 249 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 249 T2497 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 249 T2497 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 250 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 253 T2497 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 255 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 255 T2497 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 258 T2497 oasc.Overseer.start Overseer (id=88557453724286978-127.0.0.1:33446_solr-n_0000000000) starting
[junit4:junit4]   2> 258 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 259 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 260 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 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 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 261 T2519 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 261 T2497 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 262 T2497 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 274 T2518 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 277 T2497 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351279506430/collection1
[junit4:junit4]   2> 277 T2497 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 278 T2497 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 278 T2497 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 280 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351279506430/collection1/'
[junit4:junit4]   2> 280 T2497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-controljetty-1351279506430/collection1/lib/README' to classloader
[junit4:junit4]   2> 281 T2497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-controljetty-1351279506430/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 317 T2497 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 358 T2497 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 360 T2497 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 366 T2497 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 697 T2497 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 703 T2497 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 705 T2497 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 714 T2497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 717 T2497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 720 T2497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 721 T2497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 722 T2497 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351279506430/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/
[junit4:junit4]   2> 722 T2497 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@fd81a3
[junit4:junit4]   2> 723 T2497 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 724 T2497 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b3850 assuming 'simple'
[junit4:junit4]   2> 724 T2497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data forceNew:false
[junit4:junit4]   2> 724 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data
[junit4:junit4]   2> 725 T2497 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/index/
[junit4:junit4]   2> 725 T2497 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 726 T2497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/index forceNew:false
[junit4:junit4]   2> 730 T2497 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 730 T2497 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 731 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/index
[junit4:junit4]   2> 731 T2497 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 733 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 733 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 733 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 734 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 734 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 735 T2497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 735 T2497 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 736 T2497 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 736 T2497 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 737 T2497 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 737 T2497 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 737 T2497 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 738 T2497 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 738 T2497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 738 T2497 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 739 T2497 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 739 T2497 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 740 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 740 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 741 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 741 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 741 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 742 T2497 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 742 T2497 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 743 T2497 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 745 T2497 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 747 T2497 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 748 T2497 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> 749 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data
[junit4:junit4]   2> 750 T2497 oass.SolrIndexSearcher.<init> Opening Searcher@12d44cc main
[junit4:junit4]   2> 750 T2497 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 751 T2497 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 751 T2497 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 755 T2497 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 773 T2520 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12d44cc main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 776 T2497 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 777 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 779 T2497 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 779 T2497 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:33446/solr shardId:control_shard
[junit4:junit4]   2> 780 T2497 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 783 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 type:delete cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 784 T2497 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 784 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1277 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1277 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:33446_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:33446/solr"}
[junit4:junit4]   2> 1278 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 type:create cxid:0x6a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1281 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1285 T2497 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1286 T2497 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1286 T2497 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:33446/solr/collection1/
[junit4:junit4]   2> 1286 T2497 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1287 T2497 oasc.SyncStrategy.syncToMe http://127.0.0.1:33446/solr/collection1/ has no replicas
[junit4:junit4]   2> 1287 T2497 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:33446/solr/collection1/
[junit4:junit4]   2> 1287 T2497 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1290 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730002 type:create cxid:0x89 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1785 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1793 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1834 T2497 oasc.ZkController.register We are http://127.0.0.1:33446/solr/collection1/ and leader is http://127.0.0.1:33446/solr/collection1/
[junit4:junit4]   2> 1834 T2497 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:33446/solr
[junit4:junit4]   2> 1835 T2497 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 1836 T2497 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1838 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1839 T2497 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 1840 T2497 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1841 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1846 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 1850 T2497 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51870/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@164c132
[junit4:junit4]   2> 1851 T2521 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51870
[junit4:junit4]   2> 1851 T2497 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1852 T2521 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51870, initiating session
[junit4:junit4]   2> 1852 T2499 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:57823
[junit4:junit4]   2> 1852 T2499 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:57823
[junit4:junit4]   2> 1853 T2501 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9e88f3730003 with negotiated timeout 10000 for client /127.0.0.1:57823
[junit4:junit4]   2> 1854 T2521 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:51870, sessionid = 0x13a9e88f3730003, negotiated timeout = 10000
[junit4:junit4]   2> 1854 T2522 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@164c132 name:ZooKeeperConnection Watcher:127.0.0.1:51870/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1854 T2497 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1856 T2497 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1857 T2497 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 1911 T2497 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1913 T2497 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:52659
[junit4:junit4]   2> 1913 T2497 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1913 T2497 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351279508144
[junit4:junit4]   2> 1914 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351279508144/'
[junit4:junit4]   2> 1925 T2497 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1925 T2497 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1925 T2497 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351279508144
[junit4:junit4]   2> 1926 T2497 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351279508144/solr.xml
[junit4:junit4]   2> 1926 T2497 oasc.CoreContainer.<init> New CoreContainer 22259143
[junit4:junit4]   2> 1927 T2497 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351279508144/'
[junit4:junit4]   2> 1927 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351279508144/'
[junit4:junit4]   2> 1939 T2497 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1959 T2497 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51870/solr
[junit4:junit4]   2> 1960 T2497 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51870/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c694c5
[junit4:junit4]   2> 1961 T2532 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51870
[junit4:junit4]   2> 1961 T2497 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1961 T2532 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51870, initiating session
[junit4:junit4]   2> 1961 T2499 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:57826
[junit4:junit4]   2> 1962 T2499 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:57826
[junit4:junit4]   2> 1963 T2501 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9e88f3730004 with negotiated timeout 5000 for client /127.0.0.1:57826
[junit4:junit4]   2> 1963 T2532 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:51870, sessionid = 0x13a9e88f3730004, negotiated timeout = 5000
[junit4:junit4]   2> 1963 T2533 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c694c5 name:ZooKeeperConnection Watcher:127.0.0.1:51870/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1964 T2497 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1964 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1965 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1966 T2497 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52659_solr
[junit4:junit4]   2> 1967 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:52659_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:52659_solr
[junit4:junit4]   2> 1968 T2497 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52659_solr
[junit4:junit4]   2> 1969 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1969 T2522 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 1970 T2497 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1970 T2517 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 1970 T2497 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1971 T2497 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1971 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1971 T2497 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1972 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1972 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1972 T2497 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1973 T2497 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1973 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1981 T2497 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1987 T2497 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351279508144/collection1
[junit4:junit4]   2> 1988 T2497 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 1989 T2497 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1989 T2497 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 1991 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351279508144/collection1/'
[junit4:junit4]   2> 1991 T2497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty1-1351279508144/collection1/lib/README' to classloader
[junit4:junit4]   2> 1992 T2497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty1-1351279508144/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2014 T2497 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2046 T2497 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2047 T2497 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2052 T2497 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2313 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2314 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:33446_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:33446/solr"}
[junit4:junit4]   2> 2317 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2317 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2317 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2423 T2497 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2429 T2497 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2432 T2497 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2440 T2497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2442 T2497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2445 T2497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2446 T2497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2447 T2497 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351279508144/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/
[junit4:junit4]   2> 2447 T2497 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@fd81a3
[junit4:junit4]   2> 2448 T2497 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 2448 T2497 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@143ff92 assuming 'simple'
[junit4:junit4]   2> 2449 T2497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1 forceNew:false
[junit4:junit4]   2> 2449 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1
[junit4:junit4]   2> 2449 T2497 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/index/
[junit4:junit4]   2> 2450 T2497 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2450 T2497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/index forceNew:false
[junit4:junit4]   2> 2453 T2497 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2453 T2497 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 2454 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/index
[junit4:junit4]   2> 2454 T2497 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2456 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2456 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2457 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2457 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2457 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2458 T2497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2458 T2497 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2459 T2497 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2459 T2497 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2460 T2497 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2460 T2497 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2461 T2497 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2461 T2497 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2461 T2497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2462 T2497 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2462 T2497 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2463 T2497 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2463 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2463 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2464 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2464 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2465 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2465 T2497 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2466 T2497 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2466 T2497 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2469 T2497 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2470 T2497 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2471 T2497 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> 2473 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1
[junit4:junit4]   2> 2473 T2497 oass.SolrIndexSearcher.<init> Opening Searcher@1d83276 main
[junit4:junit4]   2> 2474 T2497 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2474 T2497 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2475 T2497 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2478 T2497 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2498 T2534 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d83276 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2501 T2497 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2821 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2822 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:52659_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:52659/solr"}
[junit4:junit4]   2> 2822 T2518 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 2824 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2824 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2824 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3002 T2497 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3003 T2497 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:52659/solr shardId:shard1
[junit4:junit4]   2> 3004 T2497 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3007 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730004 type:delete cxid:0x45 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 3008 T2497 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3008 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730004 type:create cxid:0x46 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3009 T2497 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3009 T2497 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3010 T2497 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:52659/solr/collection1/
[junit4:junit4]   2> 3010 T2497 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3010 T2497 oasc.SyncStrategy.syncToMe http://127.0.0.1:52659/solr/collection1/ has no replicas
[junit4:junit4]   2> 3011 T2497 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:52659/solr/collection1/
[junit4:junit4]   2> 3011 T2497 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3013 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730004 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3326 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3331 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3331 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3331 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3365 T2497 oasc.ZkController.register We are http://127.0.0.1:52659/solr/collection1/ and leader is http://127.0.0.1:52659/solr/collection1/
[junit4:junit4]   2> 3365 T2497 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52659/solr
[junit4:junit4]   2> 3366 T2497 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3366 T2497 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3368 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3369 T2497 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3370 T2497 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3371 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3425 T2497 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3427 T2497 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:49264
[junit4:junit4]   2> 3427 T2497 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3427 T2497 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351279509663
[junit4:junit4]   2> 3428 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351279509663/'
[junit4:junit4]   2> 3439 T2497 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3439 T2497 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3440 T2497 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351279509663
[junit4:junit4]   2> 3440 T2497 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351279509663/solr.xml
[junit4:junit4]   2> 3440 T2497 oasc.CoreContainer.<init> New CoreContainer 20307678
[junit4:junit4]   2> 3441 T2497 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351279509663/'
[junit4:junit4]   2> 3441 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351279509663/'
[junit4:junit4]   2> 3454 T2497 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3471 T2497 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51870/solr
[junit4:junit4]   2> 3472 T2497 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51870/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@49fd2f
[junit4:junit4]   2> 3473 T2544 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51870
[junit4:junit4]   2> 3473 T2497 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3473 T2544 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51870, initiating session
[junit4:junit4]   2> 3473 T2499 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:57833
[junit4:junit4]   2> 3474 T2499 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:57833
[junit4:junit4]   2> 3476 T2501 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9e88f3730005 with negotiated timeout 5000 for client /127.0.0.1:57833
[junit4:junit4]   2> 3476 T2544 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:51870, sessionid = 0x13a9e88f3730005, negotiated timeout = 5000
[junit4:junit4]   2> 3476 T2545 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@49fd2f name:ZooKeeperConnection Watcher:127.0.0.1:51870/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3476 T2497 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3477 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3478 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3478 T2497 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49264_solr
[junit4:junit4]   2> 3479 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:49264_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:49264_solr
[junit4:junit4]   2> 3479 T2497 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49264_solr
[junit4:junit4]   2> 3481 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3481 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3481 T2522 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3481 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3481 T2497 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3482 T2517 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3482 T2533 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3482 T2497 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3482 T2497 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3483 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3483 T2497 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3483 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3483 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3484 T2497 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3484 T2497 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3484 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3488 T2497 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3492 T2497 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351279509663/collection1
[junit4:junit4]   2> 3492 T2497 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3493 T2497 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3494 T2497 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3494 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351279509663/collection1/'
[junit4:junit4]   2> 3495 T2497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty2-1351279509663/collection1/lib/README' to classloader
[junit4:junit4]   2> 3495 T2497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty2-1351279509663/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3520 T2497 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 3557 T2497 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3558 T2497 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3563 T2497 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3833 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3834 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "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:52659_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:52659/solr"}
[junit4:junit4]   2> 3836 T2545 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3836 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3836 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3836 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 3917 T2497 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3922 T2497 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3925 T2497 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3933 T2497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3936 T2497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3939 T2497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3940 T2497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3941 T2497 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351279509663/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/
[junit4:junit4]   2> 3941 T2497 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@fd81a3
[junit4:junit4]   2> 3942 T2497 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 3942 T2497 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1dfa404 assuming 'simple'
[junit4:junit4]   2> 3942 T2497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2 forceNew:false
[junit4:junit4]   2> 3943 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2
[junit4:junit4]   2> 3943 T2497 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index/
[junit4:junit4]   2> 3944 T2497 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3944 T2497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index forceNew:false
[junit4:junit4]   2> 3947 T2497 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3948 T2497 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 3948 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index
[junit4:junit4]   2> 3949 T2497 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3950 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3951 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3951 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3952 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3952 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3953 T2497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3953 T2497 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3954 T2497 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3954 T2497 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3955 T2497 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3955 T2497 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3956 T2497 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3956 T2497 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3957 T2497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3957 T2497 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3958 T2497 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3958 T2497 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3959 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3959 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3960 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3960 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3961 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3961 T2497 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3962 T2497 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3962 T2497 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3965 T2497 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3967 T2497 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3968 T2497 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> 3970 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2
[junit4:junit4]   2> 3971 T2497 oass.SolrIndexSearcher.<init> Opening Searcher@1a376ef main
[junit4:junit4]   2> 3972 T2497 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3973 T2497 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3974 T2497 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3978 T2497 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4008 T2546 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a376ef main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4013 T2497 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4338 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4339 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:49264_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:49264/solr"}
[junit4:junit4]   2> 4341 T2545 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4341 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4341 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4342 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4514 T2497 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4514 T2497 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:49264/solr shardId:shard1
[junit4:junit4]   2> 4517 T2497 oasc.ZkController.register We are http://127.0.0.1:49264/solr/collection1/ and leader is http://127.0.0.1:52659/solr/collection1/
[junit4:junit4]   2> 4517 T2497 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49264/solr
[junit4:junit4]   2> 4517 T2497 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 4518 T2497 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C189 name=collection1 org.apache.solr.core.SolrCore@1f3a140 url=http://127.0.0.1:49264/solr/collection1 node=127.0.0.1:49264_solr
[junit4:junit4]   2> 4519 T2547 C189 P49264 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 4519 T2547 C189 P49264 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 4519 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4519 T2547 C189 P49264 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4520 T2497 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4520 T2497 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4521 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4532 T2547 C189 P49264 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:52659/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 4532 T2547 C189 P49264 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:49264/solr START replicas=[http://127.0.0.1:52659/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 4532 T2547 C189 P49264 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 4532 T2547 C189 P49264 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 4533 T2547 C189 P49264 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 4533 T2547 C189 P49264 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C190 name=collection1 org.apache.solr.core.SolrCore@1e6ead7 url=http://127.0.0.1:52659/solr/collection1 node=127.0.0.1:52659_solr
[junit4:junit4]   2> 4537 T2530 C190 P52659 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 4571 T2497 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4573 T2497 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:46819
[junit4:junit4]   2> 4574 T2497 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4574 T2497 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351279510810
[junit4:junit4]   2> 4575 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351279510810/'
[junit4:junit4]   2> 4586 T2497 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4587 T2497 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4587 T2497 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351279510810
[junit4:junit4]   2> 4587 T2497 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351279510810/solr.xml
[junit4:junit4]   2> 4588 T2497 oasc.CoreContainer.<init> New CoreContainer 16595024
[junit4:junit4]   2> 4588 T2497 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351279510810/'
[junit4:junit4]   2> 4588 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351279510810/'
[junit4:junit4]   2> 4611 T2497 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4619 T2497 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51870/solr
[junit4:junit4]   2> 4620 T2497 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51870/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1cd54c6
[junit4:junit4]   2> 4620 T2558 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51870
[junit4:junit4]   2> 4620 T2497 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4621 T2558 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51870, initiating session
[junit4:junit4]   2> 4621 T2499 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:57854
[junit4:junit4]   2> 4621 T2499 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:57854
[junit4:junit4]   2> 4623 T2501 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9e88f3730006 with negotiated timeout 5000 for client /127.0.0.1:57854
[junit4:junit4]   2> 4623 T2558 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:51870, sessionid = 0x13a9e88f3730006, negotiated timeout = 5000
[junit4:junit4]   2> 4623 T2559 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1cd54c6 name:ZooKeeperConnection Watcher:127.0.0.1:51870/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4623 T2497 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4624 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4625 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4625 T2497 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46819_solr
[junit4:junit4]   2> 4626 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:46819_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:46819_solr
[junit4:junit4]   2> 4626 T2497 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46819_solr
[junit4:junit4]   2> 4627 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4627 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4628 T2522 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4628 T2545 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4628 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4628 T2545 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4628 T2517 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4629 T2533 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 4628 T2497 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4629 T2497 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4630 T2497 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4630 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4630 T2497 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4630 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4631 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4631 T2497 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4631 T2497 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4632 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4636 T2497 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4639 T2497 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351279510810/collection1
[junit4:junit4]   2> 4640 T2497 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4640 T2497 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4641 T2497 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4641 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351279510810/collection1/'
[junit4:junit4]   2> 4642 T2497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty3-1351279510810/collection1/lib/README' to classloader
[junit4:junit4]   2> 4642 T2497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty3-1351279510810/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4668 T2497 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4704 T2497 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4714 T2497 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4720 T2497 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4846 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4847 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:49264_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:49264/solr"}
[junit4:junit4]   2> 4850 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4850 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4850 T2545 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4850 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 4850 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5066 T2497 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5072 T2497 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5074 T2497 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5085 T2497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5088 T2497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5091 T2497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5092 T2497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5093 T2497 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351279510810/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/
[junit4:junit4]   2> 5093 T2497 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@fd81a3
[junit4:junit4]   2> 5094 T2497 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 5094 T2497 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3 lockFactory=org.apache.lucene.store.NativeFSLockFactory@ceb201 assuming 'simple'
[junit4:junit4]   2> 5095 T2497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3 forceNew:false
[junit4:junit4]   2> 5095 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3
[junit4:junit4]   2> 5095 T2497 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index/
[junit4:junit4]   2> 5096 T2497 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5096 T2497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index forceNew:false
[junit4:junit4]   2> 5100 T2497 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5100 T2497 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5101 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index
[junit4:junit4]   2> 5101 T2497 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5103 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5103 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5104 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5104 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5104 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5105 T2497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5105 T2497 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5106 T2497 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5106 T2497 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5107 T2497 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5107 T2497 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5108 T2497 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5108 T2497 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5109 T2497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5109 T2497 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5110 T2497 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5110 T2497 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5111 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5112 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5112 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5113 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5113 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5114 T2497 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5114 T2497 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5115 T2497 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5118 T2497 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5119 T2497 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5120 T2497 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> 5122 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3
[junit4:junit4]   2> 5123 T2497 oass.SolrIndexSearcher.<init> Opening Searcher@2203b9 main
[junit4:junit4]   2> 5124 T2497 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5124 T2497 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5125 T2497 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5129 T2497 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5148 T2560 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2203b9 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5152 T2497 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5367 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5367 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:46819_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:46819/solr"}
[junit4:junit4]   2> 5370 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5370 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5370 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5370 T2545 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5370 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5653 T2497 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5653 T2497 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:46819/solr shardId:shard1
[junit4:junit4]   2> 5655 T2497 oasc.ZkController.register We are http://127.0.0.1:46819/solr/collection1/ and leader is http://127.0.0.1:52659/solr/collection1/
[junit4:junit4]   2> 5656 T2497 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:46819/solr
[junit4:junit4]   2> 5656 T2497 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 5657 T2497 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C191 name=collection1 org.apache.solr.core.SolrCore@1d4ff72 url=http://127.0.0.1:46819/solr/collection1 node=127.0.0.1:46819_solr
[junit4:junit4]   2> 5658 T2561 C191 P46819 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 5658 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5659 T2561 C191 P46819 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 5659 T2561 C191 P46819 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5659 T2497 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 5660 T2497 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5660 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5666 T2561 C191 P46819 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:52659/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 5666 T2561 C191 P46819 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:46819/solr START replicas=[http://127.0.0.1:52659/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 5666 T2561 C191 P46819 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 5667 T2561 C191 P46819 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 5667 T2561 C191 P46819 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 5667 T2561 C191 P46819 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5668 T2530 C190 P52659 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 5740 T2497 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5743 T2497 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:36495
[junit4:junit4]   2> 5743 T2497 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5744 T2497 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351279511951
[junit4:junit4]   2> 5745 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351279511951/'
[junit4:junit4]   2> 5763 T2497 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5764 T2497 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5764 T2497 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351279511951
[junit4:junit4]   2> 5765 T2497 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351279511951/solr.xml
[junit4:junit4]   2> 5765 T2497 oasc.CoreContainer.<init> New CoreContainer 31757539
[junit4:junit4]   2> 5766 T2497 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351279511951/'
[junit4:junit4]   2> 5766 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351279511951/'
[junit4:junit4]   2> 5805 T2497 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5816 T2497 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51870/solr
[junit4:junit4]   2> 5817 T2497 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51870/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@1df3a0b
[junit4:junit4]   2> 5818 T2572 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:51870
[junit4:junit4]   2> 5818 T2497 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5819 T2499 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:57859
[junit4:junit4]   2> 5819 T2572 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:51870, initiating session
[junit4:junit4]   2> 5819 T2499 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:57859
[junit4:junit4]   2> 5820 T2572 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:51870, sessionid = 0x13a9e88f3730007, negotiated timeout = 5000
[junit4:junit4]   2> 5820 T2501 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9e88f3730007 with negotiated timeout 5000 for client /127.0.0.1:57859
[junit4:junit4]   2> 5821 T2573 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1df3a0b name:ZooKeeperConnection Watcher:127.0.0.1:51870/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5821 T2497 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5821 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730007 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5822 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730007 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5823 T2497 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36495_solr
[junit4:junit4]   2> 5823 T2502 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9e88f3730007 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:36495_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:36495_solr
[junit4:junit4]   2> 5824 T2497 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36495_solr
[junit4:junit4]   2> 5825 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5825 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5826 T2522 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5826 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5826 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5826 T2545 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5826 T2545 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5826 T2559 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5831 T2517 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5831 T2533 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 5831 T2497 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5832 T2497 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5832 T2497 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5833 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5833 T2497 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5834 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5834 T2497 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5835 T2497 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5835 T2497 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5836 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5844 T2497 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5849 T2497 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351279511951/collection1
[junit4:junit4]   2> 5850 T2497 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5851 T2497 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5851 T2497 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5853 T2497 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351279511951/collection1/'
[junit4:junit4]   2> 5854 T2497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty4-1351279511951/collection1/lib/README' to classloader
[junit4:junit4]   2> 5855 T2497 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty4-1351279511951/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5873 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5874 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:46819_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:46819/solr"}
[junit4:junit4]   2> 5876 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5876 T2573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5876 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5876 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5876 T2545 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5876 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 5900 T2497 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5950 T2497 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5952 T2497 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5958 T2497 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6597 T2497 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6602 T2497 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6605 T2497 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6613 T2497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6617 T2497 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6621 T2497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6622 T2497 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6623 T2497 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351279511951/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/
[junit4:junit4]   2> 6623 T2497 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@fd81a3
[junit4:junit4]   2> 6624 T2497 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 6624 T2497 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ff5c6 assuming 'simple'
[junit4:junit4]   2> 6625 T2497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4 forceNew:false
[junit4:junit4]   2> 6627 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4
[junit4:junit4]   2> 6628 T2497 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index/
[junit4:junit4]   2> 6628 T2497 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6629 T2497 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index forceNew:false
[junit4:junit4]   2> 6643 T2497 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6644 T2497 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 6644 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index
[junit4:junit4]   2> 6645 T2497 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6646 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6646 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6647 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6647 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6648 T2497 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6648 T2497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6649 T2497 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6649 T2497 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6649 T2497 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6650 T2497 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6650 T2497 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6651 T2497 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6651 T2497 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6652 T2497 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6652 T2497 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6653 T2497 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6653 T2497 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6653 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6654 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6654 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6655 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6655 T2497 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6656 T2497 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6656 T2497 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6657 T2497 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6659 T2497 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6661 T2497 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6662 T2497 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> 6663 T2497 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4
[junit4:junit4]   2> 6664 T2497 oass.SolrIndexSearcher.<init> Opening Searcher@123b5c1 main
[junit4:junit4]   2> 6665 T2497 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6665 T2497 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6666 T2497 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6669 T2497 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6719 T2574 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@123b5c1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6723 T2497 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7064 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7065 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:36495_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:36495/solr"}
[junit4:junit4]   2> 7076 T2545 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7076 T2573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7076 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7076 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7076 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7076 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7225 T2497 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 7225 T2497 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:36495/solr shardId:shard1
[junit4:junit4]   2> 7230 T2497 oasc.ZkController.register We are http://127.0.0.1:36495/solr/collection1/ and leader is http://127.0.0.1:52659/solr/collection1/
[junit4:junit4]   2> 7230 T2497 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:36495/solr
[junit4:junit4]   2> 7231 T2497 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 7232 T2497 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C192 name=collection1 org.apache.solr.core.SolrCore@556bea url=http://127.0.0.1:36495/solr/collection1 node=127.0.0.1:36495_solr
[junit4:junit4]   2> 7233 T2575 C192 P36495 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 7234 T2575 C192 P36495 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 7234 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7234 T2575 C192 P36495 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7236 T2497 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 7236 T2575 C192 P36495 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:52659/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 7236 T2497 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7237 T2575 C192 P36495 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:36495/solr START replicas=[http://127.0.0.1:52659/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 7237 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7237 T2575 C192 P36495 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 7238 T2575 C192 P36495 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 7238 T2575 C192 P36495 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 7238 T2575 C192 P36495 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7239 T2528 C190 P52659 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 7243 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7244 T2497 oasc.AbstractFullDistribZkTestBase.waitForThingsToLevelOut Wait for recoveries to finish - wait 15 for each attempt
[junit4:junit4]   2> 7245 T2497 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):15
[junit4:junit4]   2> 7246 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C189_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:49264_solr, base_url=http://127.0.0.1:49264/solr}
[junit4:junit4]   2> 7539 T2547 C189 P49264 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 7539 T2547 C189 P49264 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 7539 T2547 C189 P49264 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:52659/solr/collection1/. core=collection1
[junit4:junit4]   2> 7540 T2547 C189 P49264 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7545 T2531 C190 P52659 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7546 T2531 C190 P52659 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1
[junit4:junit4]   2> 7549 T2531 C190 P52659 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7549 T2531 C190 P52659 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 7550 T2531 C190 P52659 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1
[junit4:junit4]   2> 7550 T2531 C190 P52659 oass.SolrIndexSearcher.<init> Opening Searcher@165130d realtime
[junit4:junit4]   2> 7551 T2531 C190 P52659 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7551 T2531 C190 P52659 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 6
[junit4:junit4]   2> 7552 T2547 C189 P49264 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 7553 T2547 C189 P49264 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 7555 T2531 C190 P52659 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 7555 T2531 C190 P52659 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 7556 T2547 C189 P49264 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2
[junit4:junit4]   2> 7558 T2547 C189 P49264 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7559 T2547 C189 P49264 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 7559 T2547 C189 P49264 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7572 T2547 C189 P49264 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 7572 T2547 C189 P49264 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 7573 T2547 C189 P49264 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2
[junit4:junit4]   2> 7574 T2547 C189 P49264 oass.SolrIndexSearcher.<init> Opening Searcher@186b843 main
[junit4:junit4]   2> 7574 T2547 C189 P49264 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7576 T2546 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@186b843 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 7577 T2546 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index
[junit4:junit4]   2> 7577 T2547 C189 P49264 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 7578 T2547 C189 P49264 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 7578 T2547 C189 P49264 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7580 T2547 C189 P49264 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 7582 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7583 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:36495_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:36495/solr"}
[junit4:junit4]   2> 7588 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "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:49264_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:49264/solr"}
[junit4:junit4]   2> 7602 T2545 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7602 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7602 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7602 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7602 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 7602 T2573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8248 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C191_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:46819_solr, base_url=http://127.0.0.1:46819/solr}
[junit4:junit4]   2> 8671 T2561 C191 P46819 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 8671 T2561 C191 P46819 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 8672 T2561 C191 P46819 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:52659/solr/collection1/. core=collection1
[junit4:junit4]   2> 8672 T2561 C191 P46819 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8678 T2529 C190 P52659 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8682 T2529 C190 P52659 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1
[junit4:junit4]   2> 8683 T2529 C190 P52659 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8683 T2529 C190 P52659 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 5
[junit4:junit4]   2> 8684 T2561 C191 P46819 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 8684 T2561 C191 P46819 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 8687 T2528 C190 P52659 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8688 T2561 C191 P46819 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3
[junit4:junit4]   2> 8691 T2561 C191 P46819 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8691 T2561 C191 P46819 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8691 T2561 C191 P46819 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8701 T2561 C191 P46819 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 8702 T2561 C191 P46819 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 8702 T2561 C191 P46819 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3
[junit4:junit4]   2> 8703 T2561 C191 P46819 oass.SolrIndexSearcher.<init> Opening Searcher@f75797 main
[junit4:junit4]   2> 8703 T2561 C191 P46819 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8704 T2560 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f75797 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 8704 T2560 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index
[junit4:junit4]   2> 8705 T2561 C191 P46819 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 8705 T2561 C191 P46819 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 8705 T2561 C191 P46819 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8706 T2561 C191 P46819 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 9111 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9112 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "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:46819_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:46819/solr"}
[junit4:junit4]   2> 9122 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9122 T2573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9122 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9122 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9122 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9122 T2545 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9249 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C192_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:36495_solr, base_url=http://127.0.0.1:36495/solr}
[junit4:junit4]   2> 10244 T2575 C192 P36495 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 10245 T2575 C192 P36495 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 10245 T2575 C192 P36495 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:52659/solr/collection1/. core=collection1
[junit4:junit4]   2> 10245 T2575 C192 P36495 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10248 T2530 C190 P52659 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10249 T2530 C190 P52659 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1
[junit4:junit4]   2> 10250 T2530 C190 P52659 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10250 T2530 C190 P52659 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 2
[junit4:junit4]   2> 10251 T2575 C192 P36495 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 10251 T2575 C192 P36495 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 10251 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10253 T2531 C190 P52659 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10254 T2575 C192 P36495 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4
[junit4:junit4]   2> 10256 T2575 C192 P36495 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10256 T2575 C192 P36495 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 10257 T2575 C192 P36495 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10261 T2575 C192 P36495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 10262 T2575 C192 P36495 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 10262 T2575 C192 P36495 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4
[junit4:junit4]   2> 10263 T2575 C192 P36495 oass.SolrIndexSearcher.<init> Opening Searcher@c468ad main
[junit4:junit4]   2> 10263 T2575 C192 P36495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10264 T2574 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c468ad main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 10265 T2574 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index
[junit4:junit4]   2> 10265 T2575 C192 P36495 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 10265 T2575 C192 P36495 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 10265 T2575 C192 P36495 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10267 T2575 C192 P36495 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 10628 T2518 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10629 T2518 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "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:36495_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:36495/solr"}
[junit4:junit4]   2> 10682 T2545 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10682 T2559 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10682 T2573 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10683 T2522 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10683 T2533 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10682 T2517 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11252 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C193 name=collection1 org.apache.solr.core.SolrCore@1e60429 url=http://127.0.0.1:33446/solr/collection1 node=127.0.0.1:33446_solr
[junit4:junit4]   2> 11257 T2512 C193 P33446 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11258 T2512 C193 P33446 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data
[junit4:junit4]   2> 11260 T2512 C193 P33446 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 11260 T2512 C193 P33446 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 11260 T2512 C193 P33446 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data
[junit4:junit4]   2> 11261 T2512 C193 P33446 oass.SolrIndexSearcher.<init> Opening Searcher@1b301dc main
[junit4:junit4]   2> 11261 T2512 C193 P33446 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11262 T2520 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b301dc main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 11263 T2520 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/index
[junit4:junit4]   2> 11263 T2512 C193 P33446 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 6
[junit4:junit4]   2> 11265 T2531 C190 P52659 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11265 T2531 C190 P52659 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1
[junit4:junit4]   2> 11266 T2531 C190 P52659 oass.SolrIndexSearcher.<init> Opening Searcher@1b4262a main
[junit4:junit4]   2> 11266 T2531 C190 P52659 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/index
[junit4:junit4]   2> 11267 T2531 C190 P52659 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11268 T2534 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b4262a main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4]   2> 11268 T2534 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/index
[junit4:junit4]   2> 11269 T2531 C190 P52659 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:49264/solr/collection1/, StdNode: http://127.0.0.1:46819/solr/collection1/, StdNode: http://127.0.0.1:36495/solr/collection1/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4]   2>  C191_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:46819_solr, base_url=http://127.0.0.1:46819/solr}
[junit4:junit4]   2> 11272 T2557 C191 P46819 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2>  C189_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:49264_solr, base_url=http://127.0.0.1:49264/solr}
[junit4:junit4]   2> 11274 T2543 C189 P49264 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11274 T2557 C191 P46819 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3
[junit4:junit4]   2> 11276 T2557 C191 P46819 oass.SolrIndexSearcher.<init> Opening Searcher@1e333ff main
[junit4:junit4]   2>  C192_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:36495_solr, base_url=http://127.0.0.1:36495/solr}
[junit4:junit4]   2> 11276 T2571 C192 P36495 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11276 T2557 C191 P46819 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11278 T2543 C189 P49264 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2
[junit4:junit4]   2> 11278 T2571 C192 P36495 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4
[junit4:junit4]   2> 11278 T2560 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e333ff main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 11279 T2560 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index
[junit4:junit4]   2> 11279 T2557 C191 P46819 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 7
[junit4:junit4]   2> 11279 T2571 C192 P36495 oass.SolrIndexSearcher.<init> Opening Searcher@17fe93 main
[junit4:junit4]   2> 11279 T2543 C189 P49264 oass.SolrIndexSearcher.<init> Opening Searcher@1cc1ab7 main
[junit4:junit4]   2> 11279 T2571 C192 P36495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11280 T2543 C189 P49264 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11289 T2574 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17fe93 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 11290 T2574 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index
[junit4:junit4]   2> 11290 T2546 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cc1ab7 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 11290 T2571 C192 P36495 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 14
[junit4:junit4]   2> 11290 T2546 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index
[junit4:junit4]   2> 11290 T2543 C189 P49264 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 17
[junit4:junit4]   2> 11291 T2531 C190 P52659 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 26
[junit4:junit4]   2> 11292 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11294 T2530 C190 P52659 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 11295 T2541 C189 P49264 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 11296 T2554 C191 P46819 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 11298 T2569 C192 P36495 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 13302 T2515 C193 P33446 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data
[junit4:junit4]   2> 13303 T2515 C193 P33446 oass.SolrIndexSearcher.<init> Opening Searcher@1bb74a realtime
[junit4:junit4]   2> 13304 T2515 C193 P33446 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1416919273530458112)} 0 4
[junit4:junit4]   2> 13306 T2528 C190 P52659 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1
[junit4:junit4]   2> 13309 T2568 C192 P36495 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4
[junit4:junit4]   2> 13309 T2557 C191 P46819 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3
[junit4:junit4]   2> 13309 T2540 C189 P49264 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2
[junit4:junit4]   2> 13309 T2568 C192 P36495 oass.SolrIndexSearcher.<init> Opening Searcher@3b84f5 realtime
[junit4:junit4]   2> 13310 T2540 C189 P49264 oass.SolrIndexSearcher.<init> Opening Searcher@9e9396 realtime
[junit4:junit4]   2> 13310 T2557 C191 P46819 oass.SolrIndexSearcher.<init> Opening Searcher@1eda223 realtime
[junit4:junit4]   2> 13310 T2540 C189 P49264 REQ /update {update.distrib=FROMLEADER&_version_=-1416919273535700992&update.from=http://127.0.0.1:52659/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 13310 T2557 C191 P46819 REQ /update {update.distrib=FROMLEADER&_version_=-1416919273535700992&update.from=http://127.0.0.1:52659/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 13310 T2568 C192 P36495 REQ /update {update.distrib=FROMLEADER&_version_=-1416919273535700992&update.from=http://127.0.0.1:52659/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 13312 T2528 C190 P52659 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1416919273535700992)} 0 6
[junit4:junit4]   2> 13316 T2512 C193 P33446 /update {wt=javabin&version=2} {add=[0 (1416919273544089600)]} 0 2
[junit4:junit4]   2> 13323 T2569 C192 P36495 REQ /update {distrib.from=http://127.0.0.1:52659/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 13324 T2554 C191 P46819 REQ /update {distrib.from=http://127.0.0.1:52659/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 13325 T2542 C189 P49264 REQ /update {distrib.from=http://127.0.0.1:52659/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 13326 T2530 C190 P52659 /update {wt=javabin&version=2} {add=[0 (1416919273548283904)]} 0 8
[junit4:junit4]   2> 13328 T2513 C193 P33446 /update {wt=javabin&version=2} {add=[1 (1416919273558769664)]} 0 0
[junit4:junit4]   2> 13333 T2570 C192 P36495 REQ /update {distrib.from=http://127.0.0.1:52659/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 13334 T2555 C191 P46819 REQ /update {distrib.from=http://127.0.0.1:52659/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 13334 T2543 C189 P49264 REQ /update {distrib.from=http://127.0.0.1:52659/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 13335 T2529 C190 P52659 /update {wt=javabin&version=2} {add=[1 (1416919273560866816)]} 0 5
[junit4:junit4]   2> 13339 T2514 C193 P33446 /update {wt=javabin&version=2} {add=[2 (1416919273569255424)]} 0 2
[junit4:junit4]   2> 13347 T2571 C192 P36495 REQ /update {distrib.from=http://127.0.0.1:52659/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 13347 T2556 C191 P46819 REQ /update {distrib.from=http://127.0.0.1:52659/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 13348 T2531 C190 P52659 /update {test.distrib.skip.servers=http://127.0.0.1:49264/solr/collection1/&wt=javabin&version=2} {add=[2 (1416919273573449728)]} 0 6
[junit4:junit4]   2> 13351 T2515 C193 P33446 /update {wt=javabin&version=2} {add=[3 (1416919273582886912)]} 0 0
[junit4:junit4]   2> 13356 T2568 C192 P36495 REQ /update {distrib.from=http://127.0.0.1:52659/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 13357 T2528 C190 P52659 /update {test.distrib.skip.servers=http://127.0.0.1:49264/solr/collection1/&test.distrib.skip.servers=http://127.0.0.1:46819/solr/collection1/&wt=javabin&version=2} {add=[3 (1416919273584984064)]} 0 4
[junit4:junit4]   2> 13359 T2512 C193 P33446 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13372 T2512 C193 P33446 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/index,segFN=segments_2,generation=2,filenames=[_0.fnm, _0.pos, _0.tim, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0.doc, _0.tip, _0.fdt]
[junit4:junit4]   2> 13372 T2512 C193 P33446 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0.fnm, _0.pos, _0.tim, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0.doc, _0.tip, _0.fdt]
[junit4:junit4]   2> 13373 T2512 C193 P33446 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data
[junit4:junit4]   2> 13374 T2512 C193 P33446 oass.SolrIndexSearcher.<init> Opening Searcher@8671c0 main
[junit4:junit4]   2> 13374 T2512 C193 P33446 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/index
[junit4:junit4]   2> 13375 T2512 C193 P33446 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13375 T2520 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8671c0 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C4)}
[junit4:junit4]   2> 13376 T2520 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/control/data/index
[junit4:junit4]   2> 13376 T2512 C193 P33446 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 17
[junit4:junit4]   2> 13378 T2530 C190 P52659 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13402 T2530 C190 P52659 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/index,segFN=segments_2,generation=2,filenames=[_0.fnm, _0.pos, _0.tim, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0.doc, _0.tip, _0.fdt]
[junit4:junit4]   2> 13402 T2530 C190 P52659 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0.fnm, _0.pos, _0.tim, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0.doc, _0.tip, _0.fdt]
[junit4:junit4]   2> 13403 T2530 C190 P52659 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1
[junit4:junit4]   2> 13404 T2530 C190 P52659 oass.SolrIndexSearcher.<init> Opening Searcher@ae8610 main
[junit4:junit4]   2> 13404 T2530 C190 P52659 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13405 T2534 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ae8610 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C4)}
[junit4:junit4]   2> 13405 T2534 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty1/index
[junit4:junit4]   2> 13406 T2530 C190 P52659 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:49264/solr/collection1/, StdNode: http://127.0.0.1:46819/solr/collection1/, StdNode: http://127.0.0.1:36495/solr/collection1/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4]   2> 13407 T2557 C191 P46819 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13407 T2569 C192 P36495 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13407 T2541 C189 P49264 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13421 T2557 C191 P46819 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index,segFN=segments_3,generation=3,filenames=[_0.fnm, _0.pos, _0.tim, _0_nrm.cfe, _0.fdx, _0_nrm.cfs, _0.si, _0.doc, _0.tip, _0.fdt, segments_3]
[junit4:junit4]   2> 13422 T2557 C191 P46819 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0.fnm, _0.pos, _0.tim, _0_nrm.cfe, _0.fdx, _0_nrm.cfs, _0.si, _0.doc, _0.tip, _0.fdt, segments_3]
[junit4:junit4]   2> 13422 T2557 C191 P46819 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3
[junit4:junit4]   2> 13424 T2557 C191 P46819 oass.SolrIndexSearcher.<init> Opening Searcher@137df2d main
[junit4:junit4]   2> 13424 T2557 C191 P46819 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index
[junit4:junit4]   2> 13424 T2557 C191 P46819 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13426 T2560 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@137df2d main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C3)}
[junit4:junit4]   2> 13426 T2560 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty3/index
[junit4:junit4]   2> 13426 T2557 C191 P46819 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 19
[junit4:junit4]   2> 13442 T2569 C192 P36495 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index,segFN=segments_3,generation=3,filenames=[_0.fnm, _0.pos, _0.tim, _0_nrm.cfe, _0.fdx, _0_nrm.cfs, _0.si, _0.doc, _0.tip, _0.fdt, segments_3]
[junit4:junit4]   2> 13442 T2541 C189 P49264 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index,segFN=segments_3,generation=3,filenames=[_0.fnm, _0.pos, _0.tim, _0_nrm.cfe, _0.fdx, _0_nrm.cfs, _0.si, _0.doc, _0.tip, _0.fdt, segments_3]
[junit4:junit4]   2> 13442 T2569 C192 P36495 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0.fnm, _0.pos, _0.tim, _0_nrm.cfe, _0.fdx, _0_nrm.cfs, _0.si, _0.doc, _0.tip, _0.fdt, segments_3]
[junit4:junit4]   2> 13443 T2541 C189 P49264 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0.fnm, _0.pos, _0.tim, _0_nrm.cfe, _0.fdx, _0_nrm.cfs, _0.si, _0.doc, _0.tip, _0.fdt, segments_3]
[junit4:junit4]   2> 13443 T2569 C192 P36495 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4
[junit4:junit4]   2> 13443 T2541 C189 P49264 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2
[junit4:junit4]   2> 13444 T2569 C192 P36495 oass.SolrIndexSearcher.<init> Opening Searcher@e6e435 main
[junit4:junit4]   2> 13444 T2541 C189 P49264 oass.SolrIndexSearcher.<init> Opening Searcher@97eb89 main
[junit4:junit4]   2> 13445 T2569 C192 P36495 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index
[junit4:junit4]   2> 13445 T2541 C189 P49264 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index
[junit4:junit4]   2> 13445 T2569 C192 P36495 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13445 T2541 C189 P49264 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13445 T2546 oasc.JmxMonitoredMap.put WARNING Failed to register info bean: searcher javax.management.InstanceAlreadyExistsException: solr/collection1:type=searcher,id=org.apache.solr.search.SolrIndexSearcher
[junit4:junit4]   2> 	at com.sun.jmx.mbeanserver.Repository.addMBean(Repository.java:437)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerWithRepository(DefaultMBeanServerInterceptor.java:1898)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerDynamicMBean(DefaultMBeanServerInterceptor.java:966)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerObject(DefaultMBeanServerInterceptor.java:900)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerMBean(DefaultMBeanServerInterceptor.java:324)
[junit4:junit4]   2> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.registerMBean(JmxMBeanServer.java:512)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.put(JmxMonitoredMap.java:140)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.put(JmxMonitoredMap.java:51)
[junit4:junit4]   2> 	at org.apache.solr.search.SolrIndexSearcher.register(SolrIndexSearcher.java:241)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore.registerSearcher(SolrCore.java:1747)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore.access$000(SolrCore.java:127)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore$8.call(SolrCore.java:1624)
[junit4:junit4]   2> 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
[junit4:junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
[junit4:junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[junit4:junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[junit4:junit4]   2> 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 
[junit4:junit4]   2> 13446 T2574 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e6e435 main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C4)}
[junit4:junit4]   2> 13447 T2574 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty4/index
[junit4:junit4]   2> 13448 T2569 C192 P36495 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 41
[junit4:junit4]   2> 13448 T2546 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@97eb89 main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C2)}
[junit4:junit4]   2> 13449 T2546 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351279506289/jetty2/index
[junit4:junit4]   2> 13450 T2541 C189 P49264 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 43
[junit4:junit4]   2> 13450 T2530 C190 P52659 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 72
[junit4:junit4]   2> 13451 T2497 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):1800
[junit4:junit4]   2> 13452 T2497 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> check const of shard1
[junit4:junit4]   2> client0
[junit4:junit4]   2> PROPS:127.0.0.1:52659_solr_collection1:{"shard":"shard1","roles":null,"state":"active","core":"collection1","collection":"collection1","node_name":"127.0.0.1:52659_solr","base_url":"http://127.0.0.1:52659/solr","leader":"true"}
[junit4:junit4]   2> 13454 T2529 C190 P52659 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:127.0.0.1:49264_solr_collection1:{"shard":"shard1","roles":null,"state":"active","core":"collection1","collection":"collection1","node_name":"127.0.0.1:49264_solr","base_url":"http://127.0.0.1:49264/solr"}
[junit4:junit4]   2> 13456 T2540 C189 P49264 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=2 status=0 QTime=0 
[junit4:junit4]   2>  live:true
[junit4:junit4]   2>  num:2
[junit4:junit4]   2> 
[junit4:junit4]   2> ######shard1 is not consistent.  Got 4 from http://127.0.0.1:52659/solr/collection1lastClient and got 2 from http://127.0.0.1:49264/solr/collection1
[junit4:junit4]   2> 13458 T2528 C190 P52659 REQ /select {fl=id,_version_&sort=id+asc&q=*:*&distrib=false&wt=javabin&rows=1000&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   2> 13460 T2542 C189 P49264 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> ######http://127.0.0.1:52659/solr/collection1: {numFound=4,start=0,docs=[SolrDocument{id=0, _version_=1416919273548283904}, SolrDocument{id=1, _version_=1416919273560866816}, SolrDocument{id=2, _version_=1416919273573449728}, SolrDocument{id=3, _version_=1416919273584984064}]}
[junit4:junit4]   2> ######http://127.0.0.1:49264/solr/collection1: {numFound=2,start=0,docs=[SolrDocument{id=0, _version_=1416919273548283904}, SolrDocument{id=1, _version_=1416919273560866816}]}
[junit4:junit4]   2> ###### sizes=4,2
[junit4:junit4]   2> ###### Only in http://127.0.0.1:52659/solr/collection1: [{id=2, _version_=1416919273573449728}, {id=3, _version_=1416919273584984064}]
[junit4:junit4]   2> client2
[junit4:junit4]   2> PROPS:127.0.0.1:46819_solr_collection1:{"shard":"shard1","roles":null,"state":"active","core":"collection1","collection":"collection1","node_name":"127.0.0.1:46819_solr","base_url":"http://127.0.0.1:46819/solr"}
[junit4:junit4]   2> 13463 T2554 C191 P46819 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=3 status=0 QTime=1 
[junit4:junit4]   2>  live:true
[junit4:junit4]   2>  num:3
[junit4:junit4]   2> 
[junit4:junit4]   2> client3
[junit4:junit4]   2> PROPS:127.0.0.1:36495_solr_collection1:{"shard":"shard1","roles":null,"state":"active","core":"collection1","collection":"collection1","node_name":"127.0.0.1:36495_solr","base_url":"http://127.0.0.1:36495/solr"}
[junit4:junit4]   2> 13465 T2570 C192 P36495 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> 13467 T2497 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 13471 T2556 oasha.CollectionsHandler.handleSyncShardAction Syncing shard : action=SYNCSHARD&collection=collection1&shard=shard1&wt=javabin&version=2
[junit4:junit4]   2> 13471 T2556 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 13474 T2530 oasha.CoreAdminHandler.handleRequestSyncAction I have been requested to sync up my shard
[junit4:junit4]   2> 13474 T2530 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:52659/solr/collection1/
[junit4:junit4]   2> 13474 T2530 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:52659/solr START replicas=[http://127.0.0.1:49264/solr/collection1/, http://127.0.0.1:46819/solr/collection1/, http://127.0.0.1:36495/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 13476 T2557 C191 P46819 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 13476 T2543 C189 P49264 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 13477 T2530 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:52659/solr  Received 4 versions from 127.0.0.1:46819/solr/collection1/
[junit4:junit4]   2> 13477 T2568 C192 P36495 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 13477 T2530 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:52659/solr  Our versions are newer. ourLowThreshold=1416919273535700992 otherHigh=1416919273573449728
[junit4:junit4]   2> 13477 T2530 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:52659/solr  Received 3 versions from 127.0.0.1:49264/solr/collection1/
[junit4:junit4]   2> 13478 T2530 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:52659/solr  Our versions are newer. ourLowThreshold=1416919273535700992 otherHigh=1416919273560866816
[junit4:junit4]   2> 13478 T2530 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:52659/solr  Received 5 versions from 127.0.0.1:36495/solr/collection1/
[junit4:junit4]   2> 13479 T2530 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:52659/solr  Our versions are newer. ourLowThreshold=1416919273535700992 otherHigh=1416919273573449728
[junit4:junit4]   2> 13479 T2530 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:52659/solr DONE. sync succeeded
[junit4:junit4]   2> 13480 T2530 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 13480 T2530 oasc.SyncStrategy.syncToMe http://127.0.0.1:52659/solr/collection1/: try and ask http://127.0.0.1:49264/solr/collection1/ to sync
[junit4:junit4]   2> 13481 T2530 oasc.SyncStrategy.syncToMe http://127.0.0.1:52659/solr/collection1/: try and ask http://127.0.0.1:46819/solr/collection1/ to sync
[junit4:junit4]   2> 13482 T2530 oasc.SyncStrategy.syncToMe http://127.0.0.1:52659/solr/collection1/: try and ask http://127.0.0.1:36495/solr/collection1/ to sync
[junit4:junit4]   2> 13483 T2541 C189 P49264 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:49264/solr START replicas=[http://127.0.0.1:52659/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 13484 T2555 C191 P46819 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:46819/solr START replicas=[http://127.0.0.1:52659/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 13485 T2569 C192 P36495 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:36495/solr START replicas=[http://127.0.0.1:52659/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 13486 T2531 C190 P52659 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 13486 T2529 C190 P52659 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 13486 T2528 C190 P52659 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 13487 T2541 C189 P49264 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:49264/solr  Received 5 versions from 127.0.0.1:52659/solr/collection1/
[junit4:junit4]   2> 13487 T2555 C191 P46819 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:46819/solr  Received 5 versions from 127.0.0.1:52659/solr/collection1/
[junit4:junit4]   2> 13487 T2541 C189 P49264 oasu.PeerSync.requestUpdates PeerSync: core=collection1 url=http://127.0.0.1:49264/solr Requesting updates from 127.0.0.1:52659/solr/collection1/n=2 versions=[1416919273584984064, 1416919273573449728]
[junit4:junit4]   2> 13487 T2555 C191 P46819 oasu.PeerSync.requestUpdates PeerSync: core=collection1 url=http://127.0.0.1:46819/solr Requesting updates from 127.0.0.1:52659/solr/collection1/n=1 versions=[1416919273584984064]
[junit4:junit4]   2> 13487 T2569 C192 P36495 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:36495/solr  Received 5 versions from 127.0.0.1:52659/solr/collection1/
[junit4:junit4]   2> 13489 T2569 C192 P36495 oasu.PeerSync.handleVersions PeerSync: core=collection1 url=http://127.0.0.1:36495/solr  Our versions are newer. ourLowThreshold=1416919273535700992 otherHigh=1416919273573449728
[junit4:junit4]   2> 13490 T2569 C192 P36495 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:36495/solr DONE. sync succeeded
[junit4:junit4]   2> 13490 T2569 C192 P36495 REQ /get {sync=http://127.0.0.1:52659/solr/collection1/&getVersions=100&distrib=false&qt=/get&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 13490 T2531 C190 P52659 REQ /get {distrib=false&getUpdates=1416919273584984064,1416919273573449728&qt=/get&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 13491 T2529 C190 P52659 REQ /get {distrib=false&getUpdates=1416919273584984064&qt=/get&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 13491 T2530 oasc.SyncStrategy.syncToMe http://127.0.0.1:52659/solr/collection1/:  sync completed with http://127.0.0.1:36495/solr/collection1/
[junit4:junit4]   2> 13494 T2555 C191 P46819 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:46819/solr DONE. sync succeeded
[junit4:junit4]   2> 13494 T2555 C191 P46819 REQ /get {sync=http://127.0.0.1:52659/solr/collection1/&getVersions=100&distrib=false&qt=/get&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 13494 T2541 C189 P49264 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:49264/solr DONE. sync succeeded
[junit4:junit4]   2> 13494 T2530 oasc.SyncStrategy.syncToMe http://127.0.0.1:52659/solr/collection1/:  sync completed with http://127.0.0.1:46819/solr/collection1/
[junit4:junit4]   2> 13494 T2541 C189 P49

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

 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 33309 T2497 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 33315 T2496 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4:junit4]   2> 34289 T2559 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 34290 T2559 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 34290 T2559 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> 	at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> 	at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1249)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:227)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:224)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:63)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:224)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:83)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:128)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> 
[junit4:junit4]   2> 34291 T2559 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1cd54c6 name:ZooKeeperConnection Watcher:127.0.0.1:51870/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 34291 T2559 oascc.ConnectionManager.process Client->ZooKeeper status change trigger but we are already closed
[junit4:junit4]   2> 34291 T2559 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 36126 T2573 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard1/election/88557453724286983-127.0.0.1:36495_solr_collection1-n_0000000003
[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.delete(ZooKeeper.java:734)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:144)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:141)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:63)
[junit4:junit4]   2> 	at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:141)
[junit4:junit4]   2> 	at org.apache.solr.cloud.ElectionContext.cancelElection(ElectionContext.java:63)
[junit4:junit4]   2> 	at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:243)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:155)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:99)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4]   2> 	at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:128)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> 	at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2> 
[junit4:junit4]   2> 36128 T2573 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1df3a0b name:ZooKeeperConnection Watcher:127.0.0.1:51870/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 36128 T2573 oascc.ConnectionManager.process Client->ZooKeeper status change trigger but we are already closed
[junit4:junit4]   2> 36129 T2573 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> NOTE: test params are: codec=Asserting, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=ko, timezone=Europe/Rome
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic i386/Oracle Corporation 1.7.0_07 (32-bit)/cpus=8,threads=1,free=72003184,total=205533184
[junit4:junit4]   2> NOTE: All tests run in this JVM: [LukeRequestHandlerTest, DisMaxRequestHandlerTest, TestDFRSimilarityFactory, XmlUpdateRequestHandlerTest, TestStressRecovery, HighlighterTest, SolrIndexConfigTest, TestSolrXMLSerializer, DirectUpdateHandlerTest, SolrCoreTest, PingRequestHandlerTest, TestUpdate, BasicZkTest, TestReversedWildcardFilterFactory, FullSolrCloudDistribCmdsTest, TestSolrDeletionPolicy1, TestOmitPositions, UpdateParamsTest, TestAnalyzedSuggestions, StatsComponentTest, BadIndexSchemaTest, TestSolrDeletionPolicy2, TermVectorComponentDistributedTest, BasicFunctionalityTest, TestFastOutputStream, TestSolrCoreProperties, TestDocSet, TestReload, MultiTermTest, SoftAutoCommitTest, TestReplicationHandler, FileUtilsTest, FieldMutatingUpdateProcessorTest, TestJmxIntegration, EchoParamsTest, QueryParsingTest, TestWriterPerf, IndexSchemaRuntimeFieldTest, SuggesterTSTTest, SpellingQueryConverterTest, OutputWriterTest, TestFunctionQuery, TestDistributedSearch, TestHashPartitioner, XsltUpdateRequestHandlerTest, TestCharFilters, TestLRUCache, TestBinaryField, TestCodecSupport, ZkSolrClientTest, PrimitiveFieldTypeTest, TestSurroundQueryParser, ReturnFieldsTest, ConvertedLegacyTest, ZkNodePropsTest, RequiredFieldsTest, SignatureUpdateProcessorFactoryTest, ClusterStateTest, OverseerTest, RecoveryZkTest, LeaderElectionIntegrationTest, LeaderElectionTest, DistributedSpellCheckComponentTest, TestRealTimeGet, TestDistributedGrouping, TestFaceting, WordBreakSolrSpellCheckerTest, TestRecovery, TestJoin, PeerSyncTest, TestBinaryResponseWriter, TestIndexSearcher, TestTrie, DirectSolrConnectionTest, TestRemoteStreaming, QueryEqualityTest, PolyFieldTest, DocumentBuilderTest, DistributedQueryElevationComponentTest, DistanceFunctionTest, StandardRequestHandlerTest, TermVectorComponentTest, TestValueSourceCache, DebugComponentTest, SearchHandlerTest, TestArbitraryIndexDir, LoggingHandlerTest, UniqFieldsUpdateProcessorFactoryTest, SuggesterTest, CSVRequestHandlerTest, ShowFileRequestHandlerTest, SolrCoreCheckLockOnStartupTest, TestPropInject, AlternateDirectoryTest, HighlighterConfigTest, TestPropInjectDefaults, TestXIncludeConfig, TestCollationField, NumericFieldsTest, TestJmxMonitoredMap, SpellPossibilityIteratorTest, TestPhraseSuggestions, TestPluginEnable, TestIBSimilarityFactory, TimeZoneUtilsTest, DateMathParserTest, PrimUtilsTest, TestSuggestSpellingConverter, DOMUtilTest, UUIDFieldTest, RAMDirectoryFactoryTest, TestUtils, CircularListTest, PathHierarchyTokenizerFactoryTest, ClusterStateUpdateTest, SyncSliceTest]
[junit4:junit4] Completed on J1 in 36.35s, 1 test, 1 failure <<< FAILURES!

[...truncated 16 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:335: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:178: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/module-build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1171: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:839: There were test failures: 232 suites, 961 tests, 1 failure, 7 ignored (2 assumptions)

Total time: 24 minutes 50 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.7.0_07 -server -XX:+UseSerialGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message