lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_07) - Build # 1319 - Still Failing!
Date Sat, 27 Oct 2012 00:34:08 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows/1319/
Java: 64bit/jdk1.7.0_07 -XX:+UseParallelGC

2 tests failed.
FAILED:  org.apache.solr.cloud.BasicDistributedZk2Test.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([E796CC031629CEFC:6670421B6176AEC0]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(AbstractFullDistribZkTestBase.java:1231)
	at org.apache.solr.cloud.BasicDistributedZk2Test.brindDownShardIndexSomeDocsAndRecover(BasicDistributedZk2Test.java:217)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:92)
	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)


FAILED:  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([E796CC031629CEFC:6670421B6176AEC0]: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:163)
	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 8532 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.SyncSliceTest
[junit4:junit4]   2> 3 T934 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SyncSliceTest-1351297150723
[junit4:junit4]   2> 6 T934 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 9 T935 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 10 T935 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 11 T935 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 105 T934 oasc.ZkTestServer.run start zk server on port:55252
[junit4:junit4]   2> 105 T934 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55252 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@5ddca6a9
[junit4:junit4]   2> 106 T940 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55252
[junit4:junit4]   2> 106 T934 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 108 T940 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55252, initiating session
[junit4:junit4]   2> 108 T936 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55255
[junit4:junit4]   2> 109 T936 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55255
[junit4:junit4]   2> 109 T938 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 114 T938 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9f962f0a0000 with negotiated timeout 10000 for client /127.0.0.1:55255
[junit4:junit4]   2> 114 T940 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55252, sessionid = 0x13a9f962f0a0000, negotiated timeout = 10000
[junit4:junit4]   2> 115 T941 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5ddca6a9 name:ZooKeeperConnection Watcher:127.0.0.1:55252 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 115 T934 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 115 T934 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 118 T939 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a9f962f0a0000
[junit4:junit4]   2> 123 T936 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:55255 which had sessionid 0x13a9f962f0a0000
[junit4:junit4]   2> 124 T934 oaz.ZooKeeper.close Session: 0x13a9f962f0a0000 closed
[junit4:junit4]   2> 124 T934 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55252/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@75156aa6
[junit4:junit4]   2> 126 T942 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55252
[junit4:junit4]   2> 126 T934 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 127 T941 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 131 T936 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55258
[junit4:junit4]   2> 129 T942 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55252, initiating session
[junit4:junit4]   2> 133 T936 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55258
[junit4:junit4]   2> 136 T942 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55252, sessionid = 0x13a9f962f0a0001, negotiated timeout = 10000
[junit4:junit4]   2> 137 T938 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9f962f0a0001 with negotiated timeout 10000 for client /127.0.0.1:55258
[junit4:junit4]   2> 138 T943 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@75156aa6 name:ZooKeeperConnection Watcher:127.0.0.1:55252/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 138 T934 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 139 T934 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 146 T934 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 150 T934 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 154 T934 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 159 T934 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 159 T934 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 167 T934 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 167 T934 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 173 T934 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 173 T934 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 181 T934 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 181 T934 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 187 T934 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 187 T934 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 194 T934 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 194 T934 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 202 T934 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 202 T934 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 210 T934 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 211 T934 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 221 T934 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 221 T934 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 232 T934 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 232 T934 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 241 T939 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13a9f962f0a0001
[junit4:junit4]   2> 243 T934 oaz.ZooKeeper.close Session: 0x13a9f962f0a0001 closed
[junit4:junit4]   2> 245 T936 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:55258 which had sessionid 0x13a9f962f0a0001
[junit4:junit4]   2> 245 T943 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 551 T934 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 556 T934 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55259
[junit4:junit4]   2> 556 T934 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 557 T934 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-controljetty-1351297150963
[junit4:junit4]   2> 557 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-controljetty-1351297150963\'
[junit4:junit4]   2> 588 T934 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 589 T934 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 589 T934 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-controljetty-1351297150963
[junit4:junit4]   2> 589 T934 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-controljetty-1351297150963\solr.xml
[junit4:junit4]   2> 590 T934 oasc.CoreContainer.<init> New CoreContainer 1459500694
[junit4:junit4]   2> 590 T934 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-controljetty-1351297150963\'
[junit4:junit4]   2> 591 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-controljetty-1351297150963\'
[junit4:junit4]   2> 614 T934 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 629 T934 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55252/solr
[junit4:junit4]   2> 630 T934 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55252/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@646de6e1
[junit4:junit4]   2> 631 T953 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55252
[junit4:junit4]   2> 631 T934 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 633 T953 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55252, initiating session
[junit4:junit4]   2> 633 T936 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55264
[junit4:junit4]   2> 634 T936 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55264
[junit4:junit4]   2> 646 T938 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9f962f0a0002 with negotiated timeout 5000 for client /127.0.0.1:55264
[junit4:junit4]   2> 646 T953 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55252, sessionid = 0x13a9f962f0a0002, negotiated timeout = 5000
[junit4:junit4]   2> 646 T954 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@646de6e1 name:ZooKeeperConnection Watcher:127.0.0.1:55252/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 647 T934 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 649 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 651 T934 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 654 T934 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55259_solr
[junit4:junit4]   2> 654 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55259_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55259_solr
[junit4:junit4]   2> 656 T934 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55259_solr
[junit4:junit4]   2> 660 T934 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 661 T934 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 661 T934 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 661 T934 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 661 T934 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 661 T934 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 662 T934 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 662 T934 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 662 T934 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 663 T934 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 671 T934 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 679 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 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> 681 T934 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 685 T934 oasc.Overseer.start Overseer (id=88558610070241282-127.0.0.1:55259_solr-n_0000000000) starting
[junit4:junit4]   2> 686 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 688 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 689 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 691 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 693 T956 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 694 T934 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 702 T934 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 705 T955 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 710 T934 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-controljetty-1351297150963\collection1
[junit4:junit4]   2> 711 T934 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 712 T934 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 712 T934 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 714 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-controljetty-1351297150963\collection1\'
[junit4:junit4]   2> 716 T934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-controljetty-1351297150963/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 718 T934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-controljetty-1351297150963/collection1/lib/README' to classloader
[junit4:junit4]   2> 753 T934 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 813 T934 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 815 T934 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 818 T934 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1286 T934 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1293 T934 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1295 T934 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1312 T934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1317 T934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1321 T934 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1322 T934 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1323 T934 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-controljetty-1351297150963\collection1\, dataDir=.\org.apache.solr.cloud.SyncSliceTest-1351297150721/control/data\
[junit4:junit4]   2> 1323 T934 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4bb4496d
[junit4:junit4]   2> 1324 T934 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 1325 T934 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data lockFactory=org.apache.lucene.store.NativeFSLockFactory@131ffdf2 assuming 'simple'
[junit4:junit4]   2> 1326 T934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data forceNew:false
[junit4:junit4]   2> 1326 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data
[junit4:junit4]   2> 1326 T934 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.SyncSliceTest-1351297150721/control/data\index/
[junit4:junit4]   2> 1328 T934 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1328 T934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data\index forceNew:false
[junit4:junit4]   2> 1338 T934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1338 T934 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1339 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data\index
[junit4:junit4]   2> 1340 T934 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1341 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1342 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1342 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1343 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1343 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1344 T934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1344 T934 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1344 T934 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1345 T934 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1346 T934 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1346 T934 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1346 T934 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1346 T934 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1346 T934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1347 T934 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1347 T934 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1348 T934 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1348 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1348 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1349 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1349 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1350 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1350 T934 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1351 T934 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1351 T934 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1361 T934 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1367 T934 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1368 T934 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> 1371 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data
[junit4:junit4]   2> 1372 T934 oass.SolrIndexSearcher.<init> Opening Searcher@288d2151 main
[junit4:junit4]   2> 1374 T934 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1374 T934 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1375 T934 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1393 T934 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1446 T957 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@288d2151 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1450 T934 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1451 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 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> 1456 T934 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1456 T934 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55259/solr shardId:control_shard
[junit4:junit4]   2> 1457 T934 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1468 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 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> 1469 T934 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1470 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1697 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1697 T955 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:55259_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55259/solr"}
[junit4:junit4]   2> 1699 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 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> 1713 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1964 T934 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1965 T934 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1965 T934 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55259/solr/collection1/
[junit4:junit4]   2> 1966 T934 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1966 T934 oasc.SyncStrategy.syncToMe http://127.0.0.1:55259/solr/collection1/ has no replicas
[junit4:junit4]   2> 1966 T934 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55259/solr/collection1/
[junit4:junit4]   2> 1967 T934 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1974 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0002 type:create cxid:0x89 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2211 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2271 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2327 T934 oasc.ZkController.register We are http://127.0.0.1:55259/solr/collection1/ and leader is http://127.0.0.1:55259/solr/collection1/
[junit4:junit4]   2> 2328 T934 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55259/solr
[junit4:junit4]   2> 2329 T934 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2329 T934 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2333 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2334 T934 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 2334 T934 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2334 T934 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2341 T934 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 2348 T934 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55252/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@2da86005
[junit4:junit4]   2> 2349 T958 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55252
[junit4:junit4]   2> 2349 T934 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2351 T936 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55267
[junit4:junit4]   2> 2352 T958 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55252, initiating session
[junit4:junit4]   2> 2352 T936 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55267
[junit4:junit4]   2> 2354 T938 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9f962f0a0003 with negotiated timeout 10000 for client /127.0.0.1:55267
[junit4:junit4]   2> 2354 T958 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55252, sessionid = 0x13a9f962f0a0003, negotiated timeout = 10000
[junit4:junit4]   2> 2355 T959 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2da86005 name:ZooKeeperConnection Watcher:127.0.0.1:55252/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2355 T934 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2357 T934 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2361 T934 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 2648 T934 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2652 T934 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55268
[junit4:junit4]   2> 2652 T934 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2653 T934 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty1-1351297153080
[junit4:junit4]   2> 2653 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty1-1351297153080\'
[junit4:junit4]   2> 2682 T934 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2683 T934 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2683 T934 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty1-1351297153080
[junit4:junit4]   2> 2683 T934 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty1-1351297153080\solr.xml
[junit4:junit4]   2> 2684 T934 oasc.CoreContainer.<init> New CoreContainer 1586918841
[junit4:junit4]   2> 2684 T934 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty1-1351297153080\'
[junit4:junit4]   2> 2684 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty1-1351297153080\'
[junit4:junit4]   2> 2708 T934 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2723 T934 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55252/solr
[junit4:junit4]   2> 2724 T934 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55252/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@7144ccbf
[junit4:junit4]   2> 2725 T934 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2725 T969 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55252
[junit4:junit4]   2> 2727 T969 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55252, initiating session
[junit4:junit4]   2> 2727 T936 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55273
[junit4:junit4]   2> 2728 T936 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55273
[junit4:junit4]   2> 2739 T938 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9f962f0a0004 with negotiated timeout 5000 for client /127.0.0.1:55273
[junit4:junit4]   2> 2739 T969 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55252, sessionid = 0x13a9f962f0a0004, negotiated timeout = 5000
[junit4:junit4]   2> 2739 T970 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7144ccbf name:ZooKeeperConnection Watcher:127.0.0.1:55252/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2740 T934 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2740 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2742 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2744 T934 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55268_solr
[junit4:junit4]   2> 2745 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55268_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55268_solr
[junit4:junit4]   2> 2747 T934 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55268_solr
[junit4:junit4]   2> 2751 T954 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2751 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2751 T959 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2752 T934 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2752 T934 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2753 T934 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2753 T934 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2753 T934 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2753 T934 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2754 T934 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2754 T934 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2754 T934 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2754 T934 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2766 T934 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2773 T934 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty1-1351297153080\collection1
[junit4:junit4]   2> 2774 T934 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2775 T934 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2775 T934 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2777 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty1-1351297153080\collection1\'
[junit4:junit4]   2> 2778 T934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty1-1351297153080/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2779 T934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty1-1351297153080/collection1/lib/README' to classloader
[junit4:junit4]   2> 2784 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2785 T955 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:55259_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55259/solr"}
[junit4:junit4]   2> 2793 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2793 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2794 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2821 T934 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2874 T934 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2875 T934 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2880 T934 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3416 T934 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3423 T934 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3427 T934 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3444 T934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3450 T934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3455 T934 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3457 T934 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3459 T934 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty1-1351297153080\collection1\, dataDir=.\org.apache.solr.cloud.SyncSliceTest-1351297150721/jetty1\
[junit4:junit4]   2> 3459 T934 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4bb4496d
[junit4:junit4]   2> 3459 T934 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 3461 T934 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@49be592d assuming 'simple'
[junit4:junit4]   2> 3461 T934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1 forceNew:false
[junit4:junit4]   2> 3463 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1
[junit4:junit4]   2> 3463 T934 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.SyncSliceTest-1351297150721/jetty1\index/
[junit4:junit4]   2> 3464 T934 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3464 T934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1\index forceNew:false
[junit4:junit4]   2> 3474 T934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3474 T934 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 3475 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1\index
[junit4:junit4]   2> 3475 T934 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3476 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3477 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3477 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3478 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3478 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3478 T934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3479 T934 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3480 T934 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3480 T934 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3481 T934 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3481 T934 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3481 T934 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3482 T934 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3482 T934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3483 T934 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3483 T934 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3484 T934 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3484 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3485 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3485 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3486 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3486 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3487 T934 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3487 T934 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3488 T934 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3496 T934 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3501 T934 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3502 T934 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> 3505 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1
[junit4:junit4]   2> 3508 T934 oass.SolrIndexSearcher.<init> Opening Searcher@a53ac62 main
[junit4:junit4]   2> 3510 T934 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3510 T934 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3511 T934 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3520 T934 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3572 T971 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a53ac62 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3576 T934 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3789 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3790 T955 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:55268_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55268/solr"}
[junit4:junit4]   2> 3790 T955 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 3796 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3796 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3796 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4071 T934 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 4071 T934 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55268/solr shardId:shard1
[junit4:junit4]   2> 4074 T934 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 4087 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0004 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> 4089 T934 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 4089 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0004 type:create cxid:0x46 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4093 T934 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4093 T934 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 4093 T934 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55268/solr/collection1/
[junit4:junit4]   2> 4094 T934 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 4094 T934 oasc.SyncStrategy.syncToMe http://127.0.0.1:55268/solr/collection1/ has no replicas
[junit4:junit4]   2> 4094 T934 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55268/solr/collection1/
[junit4:junit4]   2> 4095 T934 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 4102 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0004 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4295 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4308 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4308 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4308 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4356 T934 oasc.ZkController.register We are http://127.0.0.1:55268/solr/collection1/ and leader is http://127.0.0.1:55268/solr/collection1/
[junit4:junit4]   2> 4357 T934 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55268/solr
[junit4:junit4]   2> 4357 T934 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4358 T934 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4362 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4363 T934 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 4363 T934 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4364 T934 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4695 T934 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4702 T934 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55274
[junit4:junit4]   2> 4703 T934 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4703 T934 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty2-1351297155090
[junit4:junit4]   2> 4704 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty2-1351297155090\'
[junit4:junit4]   2> 4731 T934 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4731 T934 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4731 T934 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty2-1351297155090
[junit4:junit4]   2> 4731 T934 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty2-1351297155090\solr.xml
[junit4:junit4]   2> 4731 T934 oasc.CoreContainer.<init> New CoreContainer 628450718
[junit4:junit4]   2> 4731 T934 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty2-1351297155090\'
[junit4:junit4]   2> 4731 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty2-1351297155090\'
[junit4:junit4]   2> 4753 T934 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4767 T934 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55252/solr
[junit4:junit4]   2> 4767 T934 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55252/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@69c1a234
[junit4:junit4]   2> 4769 T934 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4769 T981 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55252
[junit4:junit4]   2> 4771 T981 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55252, initiating session
[junit4:junit4]   2> 4771 T936 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55279
[junit4:junit4]   2> 4772 T936 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55279
[junit4:junit4]   2> 4781 T938 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9f962f0a0005 with negotiated timeout 5000 for client /127.0.0.1:55279
[junit4:junit4]   2> 4781 T981 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55252, sessionid = 0x13a9f962f0a0005, negotiated timeout = 5000
[junit4:junit4]   2> 4781 T982 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@69c1a234 name:ZooKeeperConnection Watcher:127.0.0.1:55252/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4781 T934 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4782 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4784 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4786 T934 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55274_solr
[junit4:junit4]   2> 4787 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55274_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55274_solr
[junit4:junit4]   2> 4788 T934 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55274_solr
[junit4:junit4]   2> 4791 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 4792 T954 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4793 T959 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4793 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4793 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4793 T934 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4793 T934 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4794 T934 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4795 T934 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4795 T934 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4795 T934 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4795 T934 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4796 T934 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4796 T934 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4796 T970 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 4796 T934 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4806 T934 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4807 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4808 T955 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:55268_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55268/solr"}
[junit4:junit4]   2> 4814 T934 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty2-1351297155090\collection1
[junit4:junit4]   2> 4814 T934 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4816 T934 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4816 T934 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4820 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty2-1351297155090\collection1\'
[junit4:junit4]   2> 4822 T934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty2-1351297155090/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4822 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4823 T982 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4823 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4823 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4823 T934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty2-1351297155090/collection1/lib/README' to classloader
[junit4:junit4]   2> 4870 T934 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4939 T934 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4940 T934 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4943 T934 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5437 T934 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5446 T934 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5449 T934 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5464 T934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5469 T934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5473 T934 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5475 T934 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5476 T934 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty2-1351297155090\collection1\, dataDir=.\org.apache.solr.cloud.SyncSliceTest-1351297150721/jetty2\
[junit4:junit4]   2> 5476 T934 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4bb4496d
[junit4:junit4]   2> 5477 T934 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 5478 T934 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@15fd1069 assuming 'simple'
[junit4:junit4]   2> 5478 T934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2 forceNew:false
[junit4:junit4]   2> 5480 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2
[junit4:junit4]   2> 5480 T934 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.SyncSliceTest-1351297150721/jetty2\index/
[junit4:junit4]   2> 5481 T934 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5481 T934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index forceNew:false
[junit4:junit4]   2> 5492 T934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5493 T934 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5494 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index
[junit4:junit4]   2> 5494 T934 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5496 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5496 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5497 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5497 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5497 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5497 T934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5498 T934 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5498 T934 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5499 T934 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5499 T934 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5500 T934 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5500 T934 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5501 T934 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5501 T934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5502 T934 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5502 T934 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5502 T934 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5504 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5504 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5505 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5505 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5506 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5506 T934 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5507 T934 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5507 T934 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5517 T934 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5522 T934 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5522 T934 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> 5525 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2
[junit4:junit4]   2> 5527 T934 oass.SolrIndexSearcher.<init> Opening Searcher@210e36aa main
[junit4:junit4]   2> 5529 T934 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5529 T934 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5530 T934 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5541 T934 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5595 T983 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@210e36aa main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5598 T934 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5832 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5833 T955 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:55274_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55274/solr"}
[junit4:junit4]   2> 5841 T982 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5841 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5841 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5841 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6080 T934 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6080 T934 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55274/solr shardId:shard1
[junit4:junit4]   2> 6085 T934 oasc.ZkController.register We are http://127.0.0.1:55274/solr/collection1/ and leader is http://127.0.0.1:55268/solr/collection1/
[junit4:junit4]   2> 6085 T934 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55274/solr
[junit4:junit4]   2> 6085 T934 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 6086 T934 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C72 name=collection1 org.apache.solr.core.SolrCore@48a43a81 url=http://127.0.0.1:55274/solr/collection1 node=127.0.0.1:55274_solr
[junit4:junit4]   2> 6093 T984 C72 P55274 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 6093 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6093 T984 C72 P55274 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 6093 T984 C72 P55274 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6094 T934 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 6094 T934 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6095 T934 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6096 T984 C72 P55274 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:55268/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 6106 T984 C72 P55274 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 6133 T984 C72 P55274 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:55274/solr START replicas=[http://127.0.0.1:55268/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 6148 T984 C72 P55274 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 6153 T984 C72 P55274 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 6153 T984 C72 P55274 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 6154 T984 C72 P55274 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C73 name=collection1 org.apache.solr.core.SolrCore@4030e3d7 url=http://127.0.0.1:55268/solr/collection1 node=127.0.0.1:55268_solr
[junit4:junit4]   2> 6170 T967 C73 P55268 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=1 
[junit4:junit4]   2> 6301 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6302 T955 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:55274_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55274/solr"}
[junit4:junit4]   2> 6316 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6316 T982 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6316 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6317 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6400 T934 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 6404 T934 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55282
[junit4:junit4]   2> 6404 T934 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6404 T934 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty3-1351297156856
[junit4:junit4]   2> 6405 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty3-1351297156856\'
[junit4:junit4]   2> 6432 T934 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6433 T934 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6433 T934 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty3-1351297156856
[junit4:junit4]   2> 6433 T934 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty3-1351297156856\solr.xml
[junit4:junit4]   2> 6433 T934 oasc.CoreContainer.<init> New CoreContainer 1194276254
[junit4:junit4]   2> 6434 T934 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty3-1351297156856\'
[junit4:junit4]   2> 6434 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty3-1351297156856\'
[junit4:junit4]   2> 6451 T934 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6467 T934 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55252/solr
[junit4:junit4]   2> 6468 T934 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55252/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@25247e1e
[junit4:junit4]   2> 6470 T995 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55252
[junit4:junit4]   2> 6470 T934 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6471 T995 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55252, initiating session
[junit4:junit4]   2> 6471 T936 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55287
[junit4:junit4]   2> 6471 T936 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55287
[junit4:junit4]   2> 6476 T938 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9f962f0a0006 with negotiated timeout 5000 for client /127.0.0.1:55287
[junit4:junit4]   2> 6476 T995 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55252, sessionid = 0x13a9f962f0a0006, negotiated timeout = 5000
[junit4:junit4]   2> 6476 T996 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@25247e1e name:ZooKeeperConnection Watcher:127.0.0.1:55252/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6476 T934 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6477 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6478 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6480 T934 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55282_solr
[junit4:junit4]   2> 6481 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55282_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55282_solr
[junit4:junit4]   2> 6482 T934 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55282_solr
[junit4:junit4]   2> 6484 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 6485 T959 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6485 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6485 T982 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6485 T954 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6486 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6486 T982 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6488 T934 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6488 T970 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 6488 T934 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6489 T934 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6489 T934 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6490 T934 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6490 T934 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6490 T934 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6490 T934 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6490 T934 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6490 T934 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6502 T934 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6507 T934 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty3-1351297156856\collection1
[junit4:junit4]   2> 6508 T934 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6509 T934 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6509 T934 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6510 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty3-1351297156856\collection1\'
[junit4:junit4]   2> 6513 T934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty3-1351297156856/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6513 T934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty3-1351297156856/collection1/lib/README' to classloader
[junit4:junit4]   2> 6549 T934 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 6603 T934 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6605 T934 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6607 T934 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7127 T934 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 7133 T934 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7136 T934 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7151 T934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7155 T934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7170 T934 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7173 T934 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7175 T934 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty3-1351297156856\collection1\, dataDir=.\org.apache.solr.cloud.SyncSliceTest-1351297150721/jetty3\
[junit4:junit4]   2> 7175 T934 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4bb4496d
[junit4:junit4]   2> 7175 T934 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 7176 T934 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5e6a6e19 assuming 'simple'
[junit4:junit4]   2> 7177 T934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3 forceNew:false
[junit4:junit4]   2> 7177 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3
[junit4:junit4]   2> 7177 T934 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.SyncSliceTest-1351297150721/jetty3\index/
[junit4:junit4]   2> 7179 T934 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 7179 T934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index forceNew:false
[junit4:junit4]   2> 7191 T934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7191 T934 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 7192 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index
[junit4:junit4]   2> 7192 T934 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 7194 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7194 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 7195 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7195 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 7195 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 7195 T934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7195 T934 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7197 T934 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7197 T934 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7198 T934 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 7198 T934 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 7199 T934 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7199 T934 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 7200 T934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 7200 T934 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 7201 T934 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7201 T934 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7202 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7202 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7202 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7202 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7203 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7203 T934 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7204 T934 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 7204 T934 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 7211 T934 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7214 T934 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 7215 T934 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> 7217 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3
[junit4:junit4]   2> 7220 T934 oass.SolrIndexSearcher.<init> Opening Searcher@426014d5 main
[junit4:junit4]   2> 7221 T934 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7221 T934 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7223 T934 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 7234 T934 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 7289 T997 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@426014d5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7293 T934 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7671 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7672 T955 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:55282_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55282/solr"}
[junit4:junit4]   2> 7686 T982 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7686 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7686 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7687 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7687 T996 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7738 T934 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 7739 T934 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55282/solr shardId:shard1
[junit4:junit4]   2> 7747 T934 oasc.ZkController.register We are http://127.0.0.1:55282/solr/collection1/ and leader is http://127.0.0.1:55268/solr/collection1/
[junit4:junit4]   2> 7747 T934 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55282/solr
[junit4:junit4]   2> 7748 T934 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 7749 T934 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C74 name=collection1 org.apache.solr.core.SolrCore@2468d4cb url=http://127.0.0.1:55282/solr/collection1 node=127.0.0.1:55282_solr
[junit4:junit4]   2> 7750 T998 C74 P55282 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 7751 T998 C74 P55282 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 7751 T998 C74 P55282 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7753 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7755 T998 C74 P55282 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:55268/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 7756 T998 C74 P55282 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:55282/solr START replicas=[http://127.0.0.1:55268/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 7756 T934 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 7757 T998 C74 P55282 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 7757 T934 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7758 T998 C74 P55282 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 7759 T998 C74 P55282 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 7759 T934 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7759 T998 C74 P55282 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7767 T967 C73 P55268 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 8018 T934 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 8021 T934 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:55289
[junit4:junit4]   2> 8022 T934 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8022 T934 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty4-1351297158491
[junit4:junit4]   2> 8022 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty4-1351297158491\'
[junit4:junit4]   2> 8045 T934 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8045 T934 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8045 T934 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty4-1351297158491
[junit4:junit4]   2> 8045 T934 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty4-1351297158491\solr.xml
[junit4:junit4]   2> 8046 T934 oasc.CoreContainer.<init> New CoreContainer 1751528021
[junit4:junit4]   2> 8046 T934 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty4-1351297158491\'
[junit4:junit4]   2> 8046 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty4-1351297158491\'
[junit4:junit4]   2> 8067 T934 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8080 T934 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55252/solr
[junit4:junit4]   2> 8080 T934 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55252/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@2d8617ae
[junit4:junit4]   2> 8082 T1009 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55252
[junit4:junit4]   2> 8082 T934 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8084 T1009 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:55252, initiating session
[junit4:junit4]   2> 8084 T936 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:55294
[junit4:junit4]   2> 8084 T936 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:55294
[junit4:junit4]   2> 8093 T1009 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:55252, sessionid = 0x13a9f962f0a0007, negotiated timeout = 5000
[junit4:junit4]   2> 8093 T938 oazs.NIOServerCnxn.finishSessionInit Established session 0x13a9f962f0a0007 with negotiated timeout 5000 for client /127.0.0.1:55294
[junit4:junit4]   2> 8093 T1010 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2d8617ae name:ZooKeeperConnection Watcher:127.0.0.1:55252/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8093 T934 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8094 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0007 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8096 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0007 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8098 T934 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55289_solr
[junit4:junit4]   2> 8099 T939 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13a9f962f0a0007 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55289_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55289_solr
[junit4:junit4]   2> 8099 T934 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55289_solr
[junit4:junit4]   2> 8102 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8102 T996 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8102 T982 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8103 T982 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8103 T954 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8103 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8103 T959 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8103 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8104 T934 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8105 T934 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8105 T970 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8105 T996 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8105 T934 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8105 T934 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8105 T934 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8105 T934 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8106 T934 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8106 T934 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8106 T934 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8107 T934 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8117 T934 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8122 T934 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty4-1351297158491\collection1
[junit4:junit4]   2> 8122 T934 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8124 T934 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8124 T934 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8126 T934 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty4-1351297158491\collection1\'
[junit4:junit4]   2> 8127 T934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty4-1351297158491/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8127 T934 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.SyncSliceTest-jetty4-1351297158491/collection1/lib/README' to classloader
[junit4:junit4]   2> 8140 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8141 T955 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:55282_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55282/solr"}
[junit4:junit4]   2> 8146 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8146 T982 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8147 T1010 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8147 T996 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8148 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8148 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8165 T934 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 8209 T934 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 8210 T934 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8214 T934 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 8661 T934 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 8669 T934 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8671 T934 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8687 T934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8691 T934 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8694 T934 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8695 T934 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8695 T934 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-jetty4-1351297158491\collection1\, dataDir=.\org.apache.solr.cloud.SyncSliceTest-1351297150721/jetty4\
[junit4:junit4]   2> 8695 T934 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4bb4496d
[junit4:junit4]   2> 8696 T934 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4:junit4]   2> 8696 T934 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c0540fa assuming 'simple'
[junit4:junit4]   2> 8698 T934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4 forceNew:false
[junit4:junit4]   2> 8698 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4
[junit4:junit4]   2> 8699 T934 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.SyncSliceTest-1351297150721/jetty4\index/
[junit4:junit4]   2> 8699 T934 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 8700 T934 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index forceNew:false
[junit4:junit4]   2> 8707 T934 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8707 T934 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8707 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index
[junit4:junit4]   2> 8707 T934 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 8709 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 8710 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 8710 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 8711 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 8711 T934 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 8712 T934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 8712 T934 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 8713 T934 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 8713 T934 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 8714 T934 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 8714 T934 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 8714 T934 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 8714 T934 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 8714 T934 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 8715 T934 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 8715 T934 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 8716 T934 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8716 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8717 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8717 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8718 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8718 T934 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8719 T934 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8719 T934 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 8720 T934 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 8728 T934 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 8733 T934 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 8733 T934 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> 8736 T934 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4
[junit4:junit4]   2> 8738 T934 oass.SolrIndexSearcher.<init> Opening Searcher@1f2ccc78 main
[junit4:junit4]   2> 8739 T934 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 8739 T934 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8740 T934 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 8749 T934 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 8797 T1011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f2ccc78 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8799 T934 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2>  C72_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:55274_solr, base_url=http://127.0.0.1:55274/solr}
[junit4:junit4]   2> 8821 T984 C72 P55274 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 8821 T984 C72 P55274 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 8821 T984 C72 P55274 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:55268/solr/collection1/. core=collection1
[junit4:junit4]   2> 8822 T984 C72 P55274 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8830 T965 C73 P55268 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8831 T965 C73 P55268 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1
[junit4:junit4]   2> 8835 T965 C73 P55268 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8836 T965 C73 P55268 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8836 T965 C73 P55268 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1
[junit4:junit4]   2> 8836 T965 C73 P55268 oass.SolrIndexSearcher.<init> Opening Searcher@e9348b5 realtime
[junit4:junit4]   2> 8836 T965 C73 P55268 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8836 T965 C73 P55268 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 7
[junit4:junit4]   2> 8837 T984 C72 P55274 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 8837 T984 C72 P55274 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 8839 T966 C73 P55268 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 8839 T966 C73 P55268 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 8840 T984 C72 P55274 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2
[junit4:junit4]   2> 8842 T984 C72 P55274 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8843 T984 C72 P55274 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8843 T984 C72 P55274 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8848 T984 C72 P55274 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 8848 T984 C72 P55274 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 8848 T984 C72 P55274 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2
[junit4:junit4]   2> 8851 T984 C72 P55274 oass.SolrIndexSearcher.<init> Opening Searcher@369ae836 main
[junit4:junit4]   2> 8852 T984 C72 P55274 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8852 T983 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@369ae836 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 8852 T983 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index
[junit4:junit4]   2> 8853 T984 C72 P55274 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 8853 T984 C72 P55274 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 8853 T984 C72 P55274 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8855 T984 C72 P55274 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 9044 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9045 T955 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:55289_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55289/solr"}
[junit4:junit4]   2> 9053 T955 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:55274_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55274/solr"}
[junit4:junit4]   2> 9060 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9060 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9060 T982 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9060 T996 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9060 T1010 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9060 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9245 T934 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 9245 T934 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:55289/solr shardId:shard1
[junit4:junit4]   2> 9249 T934 oasc.ZkController.register We are http://127.0.0.1:55289/solr/collection1/ and leader is http://127.0.0.1:55268/solr/collection1/
[junit4:junit4]   2> 9250 T934 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55289/solr
[junit4:junit4]   2> 9250 T934 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 9250 T934 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C75 name=collection1 org.apache.solr.core.SolrCore@7a5dacf1 url=http://127.0.0.1:55289/solr/collection1 node=127.0.0.1:55289_solr
[junit4:junit4]   2> 9251 T1012 C75 P55289 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 9252 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9252 T1012 C75 P55289 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 9252 T1012 C75 P55289 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9252 T934 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 9252 T934 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 9253 T934 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9253 T1012 C75 P55289 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:55268/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 9253 T1012 C75 P55289 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:55289/solr START replicas=[http://127.0.0.1:55268/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 9254 T1012 C75 P55289 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 9254 T1012 C75 P55289 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 9254 T1012 C75 P55289 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 9254 T1012 C75 P55289 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9257 T964 C73 P55268 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=0 
[junit4:junit4]   2> 9262 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9264 T934 oasc.AbstractFullDistribZkTestBase.waitForThingsToLevelOut Wait for recoveries to finish - wait 15 for each attempt
[junit4:junit4]   2> 9264 T934 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):15
[junit4:junit4]   2> 9265 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9512 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9513 T955 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:55289_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55289/solr"}
[junit4:junit4]   2> 9519 T982 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9519 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9520 T1010 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9520 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9520 T996 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9519 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10153 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C74_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:55282_solr, base_url=http://127.0.0.1:55282/solr}
[junit4:junit4]   2> 10437 T998 C74 P55282 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 10437 T998 C74 P55282 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 10437 T998 C74 P55282 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:55268/solr/collection1/. core=collection1
[junit4:junit4]   2> 10438 T998 C74 P55282 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10450 T967 C73 P55268 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10454 T967 C73 P55268 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1
[junit4:junit4]   2> 10455 T967 C73 P55268 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10455 T967 C73 P55268 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 5
[junit4:junit4]   2> 10457 T998 C74 P55282 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 10457 T998 C74 P55282 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 10461 T965 C73 P55268 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 10463 T998 C74 P55282 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3
[junit4:junit4]   2> 10470 T998 C74 P55282 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10472 T998 C74 P55282 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 10472 T998 C74 P55282 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10476 T998 C74 P55282 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 10476 T998 C74 P55282 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 10477 T998 C74 P55282 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3
[junit4:junit4]   2> 10481 T998 C74 P55282 oass.SolrIndexSearcher.<init> Opening Searcher@3d41d172 main
[junit4:junit4]   2> 10482 T998 C74 P55282 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10482 T997 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3d41d172 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 10482 T997 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index
[junit4:junit4]   2> 10483 T998 C74 P55282 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 10483 T998 C74 P55282 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 10483 T998 C74 P55282 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10485 T998 C74 P55282 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 10859 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10860 T955 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:55282_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55282/solr"}
[junit4:junit4]   2> 10872 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10872 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10872 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10872 T1010 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10872 T996 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10872 T982 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11040 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C75_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:55289_solr, base_url=http://127.0.0.1:55289/solr}
[junit4:junit4]   2> 11921 T1012 C75 P55289 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 11921 T1012 C75 P55289 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 11921 T1012 C75 P55289 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:55268/solr/collection1/. core=collection1
[junit4:junit4]   2> 11921 T1012 C75 P55289 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 11927 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11930 T967 C73 P55268 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11931 T967 C73 P55268 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1
[junit4:junit4]   2> 11932 T967 C73 P55268 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11932 T967 C73 P55268 /update {waitSearcher=true&openSearcher=false&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 2
[junit4:junit4]   2> 11933 T1012 C75 P55289 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 11933 T1012 C75 P55289 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 11935 T965 C73 P55268 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 11936 T1012 C75 P55289 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4
[junit4:junit4]   2> 11940 T1012 C75 P55289 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 11941 T1012 C75 P55289 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 11941 T1012 C75 P55289 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11945 T1012 C75 P55289 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 11946 T1012 C75 P55289 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 11947 T1012 C75 P55289 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4
[junit4:junit4]   2> 11950 T1012 C75 P55289 oass.SolrIndexSearcher.<init> Opening Searcher@5cba0e34 main
[junit4:junit4]   2> 11950 T1012 C75 P55289 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11950 T1011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5cba0e34 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 11951 T1011 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index
[junit4:junit4]   2> 11951 T1012 C75 P55289 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 11951 T1012 C75 P55289 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 11951 T1012 C75 P55289 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11954 T1012 C75 P55289 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 12213 T955 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12214 T955 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:55289_solr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55289/solr"}
[junit4:junit4]   2> 12307 T959 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12307 T970 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12308 T996 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12308 T1010 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12308 T954 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12307 T982 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12815 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C76 name=collection1 org.apache.solr.core.SolrCore@47fc838a url=http://127.0.0.1:55259/solr/collection1 node=127.0.0.1:55259_solr
[junit4:junit4]   2> 12824 T951 C76 P55259 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12825 T951 C76 P55259 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data
[junit4:junit4]   2> 12829 T951 C76 P55259 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12830 T951 C76 P55259 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 12830 T951 C76 P55259 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data
[junit4:junit4]   2> 12832 T951 C76 P55259 oass.SolrIndexSearcher.<init> Opening Searcher@48b0ecd1 main
[junit4:junit4]   2> 12832 T951 C76 P55259 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12833 T957 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@48b0ecd1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 12833 T957 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data\index
[junit4:junit4]   2> 12834 T951 C76 P55259 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 11
[junit4:junit4]   2> 12835 T966 C73 P55268 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12836 T966 C73 P55268 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1
[junit4:junit4]   2> 12838 T966 C73 P55268 oass.SolrIndexSearcher.<init> Opening Searcher@6cb3959f main
[junit4:junit4]   2> 12838 T966 C73 P55268 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1\index
[junit4:junit4]   2> 12838 T966 C73 P55268 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12839 T971 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6cb3959f main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4]   2> 12839 T971 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1\index
[junit4:junit4]   2> 12848 T966 C73 P55268 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:55274/solr/collection1/, StdNode: http://127.0.0.1:55282/solr/collection1/, StdNode: http://127.0.0.1:55289/solr/collection1/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4]   2>  C75_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:55289_solr, base_url=http://127.0.0.1:55289/solr}
[junit4:junit4]   2> 12867 T1003 C75 P55289 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2>  C74_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:55282_solr, base_url=http://127.0.0.1:55282/solr}
[junit4:junit4]   2> 12869 T992 C74 P55282 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12871 T1003 C75 P55289 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4
[junit4:junit4]   2> 12872 T1003 C75 P55289 oass.SolrIndexSearcher.<init> Opening Searcher@7c9cec4d main
[junit4:junit4]   2> 12872 T1003 C75 P55289 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2>  C72_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:55274_solr, base_url=http://127.0.0.1:55274/solr}
[junit4:junit4]   2> 12874 T980 C72 P55274 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12874 T1011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7c9cec4d main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 12874 T1011 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index
[junit4:junit4]   2> 12875 T1003 C75 P55289 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 8
[junit4:junit4]   2> 12875 T992 C74 P55282 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3
[junit4:junit4]   2> 12878 T980 C72 P55274 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2
[junit4:junit4]   2> 12879 T992 C74 P55282 oass.SolrIndexSearcher.<init> Opening Searcher@64e217b4 main
[junit4:junit4]   2> 12879 T992 C74 P55282 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12880 T997 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@64e217b4 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 12881 T980 C72 P55274 oass.SolrIndexSearcher.<init> Opening Searcher@4ff433de main
[junit4:junit4]   2> 12881 T997 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index
[junit4:junit4]   2> 12881 T980 C72 P55274 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12881 T992 C74 P55282 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 12
[junit4:junit4]   2> 12882 T983 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ff433de main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 12882 T983 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index
[junit4:junit4]   2> 12883 T980 C72 P55274 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 9
[junit4:junit4]   2> 12883 T966 C73 P55268 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 48
[junit4:junit4]   2> 12884 T934 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12887 T968 C73 P55268 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 12890 T975 C72 P55274 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 12891 T993 C74 P55282 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 12894 T1005 C75 P55289 REQ /select {distrib=false&wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 14671 T949 C76 P55259 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data
[junit4:junit4]   2> 14672 T949 C76 P55259 oass.SolrIndexSearcher.<init> Opening Searcher@400cad86 realtime
[junit4:junit4]   2> 14672 T949 C76 P55259 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1416937776492838912)} 0 3
[junit4:junit4]   2> 14676 T963 C73 P55268 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1
[junit4:junit4]   2> 14682 T991 C74 P55282 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3
[junit4:junit4]   2> 14683 T991 C74 P55282 oass.SolrIndexSearcher.<init> Opening Searcher@263c2506 realtime
[junit4:junit4]   2> 14683 T991 C74 P55282 REQ /update {update.distrib=FROMLEADER&_version_=-1416937776498081792&update.from=http://127.0.0.1:55268/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14683 T1008 C75 P55289 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4
[junit4:junit4]   2> 14683 T1008 C75 P55289 oass.SolrIndexSearcher.<init> Opening Searcher@770db96f realtime
[junit4:junit4]   2> 14684 T1008 C75 P55289 REQ /update {update.distrib=FROMLEADER&_version_=-1416937776498081792&update.from=http://127.0.0.1:55268/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14686 T979 C72 P55274 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2
[junit4:junit4]   2> 14686 T979 C72 P55274 oass.SolrIndexSearcher.<init> Opening Searcher@3261b071 realtime
[junit4:junit4]   2> 14686 T979 C72 P55274 REQ /update {update.distrib=FROMLEADER&_version_=-1416937776498081792&update.from=http://127.0.0.1:55268/solr/collection1/&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 14686 T963 C73 P55268 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1416937776498081792)} 0 12
[junit4:junit4]   2> 14692 T950 C76 P55259 /update {wt=javabin&version=2} {add=[0 (1416937776515907584)]} 0 1
[junit4:junit4]   2> 14709 T980 C72 P55274 REQ /update {distrib.from=http://127.0.0.1:55268/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 14711 T1004 C75 P55289 REQ /update {distrib.from=http://127.0.0.1:55268/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 14711 T992 C74 P55282 REQ /update {distrib.from=http://127.0.0.1:55268/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 14712 T966 C73 P55268 /update {wt=javabin&version=2} {add=[0 (1416937776521150464)]} 0 16
[junit4:junit4]   2> 14717 T951 C76 P55259 /update {wt=javabin&version=2} {add=[1 (1416937776541073408)]} 0 2
[junit4:junit4]   2> 14724 T993 C74 P55282 REQ /update {distrib.from=http://127.0.0.1:55268/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14724 T975 C72 P55274 REQ /update {distrib.from=http://127.0.0.1:55268/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14724 T1003 C75 P55289 REQ /update {distrib.from=http://127.0.0.1:55268/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 14724 T968 C73 P55268 /update {wt=javabin&version=2} {add=[1 (1416937776544219136)]} 0 6
[junit4:junit4]   2> 14727 T952 C76 P55259 /update {wt=javabin&version=2} {add=[2 (1416937776552607744)]} 0 1
[junit4:junit4]   2> 14732 T1006 C75 P55289 REQ /update {distrib.from=http://127.0.0.1:55268/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 14732 T994 C74 P55282 REQ /update {distrib.from=http://127.0.0.1:55268/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 14732 T967 C73 P55268 /update {test.distrib.skip.servers=http://127.0.0.1:55274/solr/collection1/&wt=javabin&version=2} {add=[2 (1416937776555753472)]} 0 3
[junit4:junit4]   2> 14736 T947 C76 P55259 /update {wt=javabin&version=2} {add=[3 (1416937776562044928)]} 0 1
[junit4:junit4]   2> 14741 T1007 C75 P55289 REQ /update {distrib.from=http://127.0.0.1:55268/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 14741 T964 C73 P55268 /update {test.distrib.skip.servers=http://127.0.0.1:55274/solr/collection1/&test.distrib.skip.servers=http://127.0.0.1:55282/solr/collection1/&wt=javabin&version=2} {add=[3 (1416937776564142080)]} 0 4
[junit4:junit4]   2> 14743 T948 C76 P55259 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14798 T948 C76 P55259 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data\index,segFN=segments_2,generation=2,filenames=[_0_Lucene41_0.pos, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.tii, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0_NestedPulsing_0.doc, _0_MockRandom_0.tib, _0.fnm, _0_MockFixedIntBlock_0.pos, _0_nrm.cfs, _0_MockRandom_0.skp, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.tiv, _0_Lucene41_0.doc, _0_MockFixedIntBlock_0.doc, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _0_nrm.cfe, segments_2, _0.fdx, _0_MockRandom_0.pyl, _0.fdt]
[junit4:junit4]   2> 14799 T948 C76 P55259 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0_Lucene41_0.pos, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.tii, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0_NestedPulsing_0.doc, _0_MockRandom_0.tib, _0.fnm, _0_MockFixedIntBlock_0.pos, _0_nrm.cfs, _0_MockRandom_0.skp, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.tiv, _0_Lucene41_0.doc, _0_MockFixedIntBlock_0.doc, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _0_nrm.cfe, segments_2, _0.fdx, _0_MockRandom_0.pyl, _0.fdt]
[junit4:junit4]   2> 14801 T948 C76 P55259 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data
[junit4:junit4]   2> 14807 T948 C76 P55259 oass.SolrIndexSearcher.<init> Opening Searcher@64c1ab11 main
[junit4:junit4]   2> 14807 T948 C76 P55259 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data\index
[junit4:junit4]   2> 14809 T948 C76 P55259 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14809 T957 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@64c1ab11 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C4)}
[junit4:junit4]   2> 14809 T957 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\control\data\index
[junit4:junit4]   2> 14811 T948 C76 P55259 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 68
[junit4:junit4]   2> 14814 T965 C73 P55268 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14857 T965 C73 P55268 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1\index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1\index,segFN=segments_2,generation=2,filenames=[_0_Lucene41_0.pos, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.tii, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0_NestedPulsing_0.doc, _0_MockRandom_0.tib, _0.fnm, _0_MockFixedIntBlock_0.pos, _0_nrm.cfs, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.sd, _0_Lucene41_0.doc, _0_MockRandom_0.doc, _0_MockFixedIntBlock_0.doc, _0_nrm.cfe, _0_MockRandom_0.pos, _0_MockRandom_0.tii, segments_2, _0.fdx, _0.fdt]
[junit4:junit4]   2> 14858 T965 C73 P55268 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[_0_Lucene41_0.pos, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.tii, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0_NestedPulsing_0.doc, _0_MockRandom_0.tib, _0.fnm, _0_MockFixedIntBlock_0.pos, _0_nrm.cfs, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.sd, _0_Lucene41_0.doc, _0_MockRandom_0.doc, _0_MockFixedIntBlock_0.doc, _0_nrm.cfe, _0_MockRandom_0.pos, _0_MockRandom_0.tii, segments_2, _0.fdx, _0.fdt]
[junit4:junit4]   2> 14859 T965 C73 P55268 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1
[junit4:junit4]   2> 14865 T965 C73 P55268 oass.SolrIndexSearcher.<init> Opening Searcher@10c3b2c2 main
[junit4:junit4]   2> 14867 T965 C73 P55268 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14867 T971 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10c3b2c2 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C4)}
[junit4:junit4]   2> 14867 T971 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty1\index
[junit4:junit4]   2> 14868 T965 C73 P55268 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:55274/solr/collection1/, StdNode: http://127.0.0.1:55282/solr/collection1/, StdNode: http://127.0.0.1:55289/solr/collection1/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4]   2> 14870 T976 C72 P55274 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14870 T989 C74 P55282 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14871 T1008 C75 P55289 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14934 T1008 C75 P55289 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index,segFN=segments_3,generation=3,filenames=[_0_Lucene41_0.pos, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.tii, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0_NestedPulsing_0.doc, _0_MockRandom_0.tib, _0.fnm, _0_MockFixedIntBlock_0.pos, _0_nrm.cfs, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.sd, _0_Lucene41_0.doc, _0_MockRandom_0.doc, _0_MockFixedIntBlock_0.doc, _0_nrm.cfe, _0_MockRandom_0.pos, _0_MockRandom_0.tii, _0.fdx, _0.fdt, segments_3]
[junit4:junit4]   2> 14934 T1008 C75 P55289 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0_Lucene41_0.pos, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.tii, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0_NestedPulsing_0.doc, _0_MockRandom_0.tib, _0.fnm, _0_MockFixedIntBlock_0.pos, _0_nrm.cfs, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.sd, _0_Lucene41_0.doc, _0_MockRandom_0.doc, _0_MockFixedIntBlock_0.doc, _0_nrm.cfe, _0_MockRandom_0.pos, _0_MockRandom_0.tii, _0.fdx, _0.fdt, segments_3]
[junit4:junit4]   2> 14936 T1008 C75 P55289 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4
[junit4:junit4]   2> 14941 T989 C74 P55282 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index,segFN=segments_3,generation=3,filenames=[_0_Lucene41_0.pos, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.tii, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0_NestedPulsing_0.doc, _0_MockRandom_0.tib, _0.fnm, _0_MockFixedIntBlock_0.pos, _0_nrm.cfs, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.tiv, _0_Lucene41_0.doc, _0_MockRandom_0.doc, _0_MockFixedIntBlock_0.doc, _0_MockRandom_0.pos, _0_nrm.cfe, _0.fdx, _0.fdt, segments_3]
[junit4:junit4]   2> 14941 T989 C74 P55282 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0_Lucene41_0.pos, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.tii, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0_NestedPulsing_0.doc, _0_MockRandom_0.tib, _0.fnm, _0_MockFixedIntBlock_0.pos, _0_nrm.cfs, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.tiv, _0_Lucene41_0.doc, _0_MockRandom_0.doc, _0_MockFixedIntBlock_0.doc, _0_MockRandom_0.pos, _0_nrm.cfe, _0.fdx, _0.fdt, segments_3]
[junit4:junit4]   2> 14941 T989 C74 P55282 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3
[junit4:junit4]   2> 14943 T1008 C75 P55289 oass.SolrIndexSearcher.<init> Opening Searcher@2f6cbd0b main
[junit4:junit4]   2> 14944 T1008 C75 P55289 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index
[junit4:junit4]   2> 14944 T1008 C75 P55289 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14946 T1011 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2f6cbd0b main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C4)}
[junit4:junit4]   2> 14946 T1011 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty4\index
[junit4:junit4]   2> 14946 T1008 C75 P55289 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 75
[junit4:junit4]   2> 14949 T989 C74 P55282 oass.SolrIndexSearcher.<init> Opening Searcher@2fb83f27 main
[junit4:junit4]   2> 14949 T989 C74 P55282 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index
[junit4:junit4]   2> 14949 T989 C74 P55282 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14951 T997 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2fb83f27 main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C3)}
[junit4:junit4]   2> 14951 T997 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty3\index
[junit4:junit4]   2> 14951 T989 C74 P55282 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 81
[junit4:junit4]   2> 14953 T976 C72 P55274 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index,segFN=segments_3,generation=3,filenames=[_0_Lucene41_0.pos, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.tii, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0_NestedPulsing_0.doc, _0_MockRandom_0.tib, _0.fnm, _0_MockFixedIntBlock_0.pos, _0_nrm.cfs, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.sd, _0_Lucene41_0.doc, _0_MockRandom_0.doc, _0_MockFixedIntBlock_0.doc, _0_nrm.cfe, _0_MockRandom_0.pos, _0_MockRandom_0.tii, _0.fdx, _0.fdt, segments_3]
[junit4:junit4]   2> 14953 T976 C72 P55274 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0_Lucene41_0.pos, _0_MockFixedIntBlock_0.tib, _0_NestedPulsing_0.tip, _0_NestedPulsing_0.tim, _0_NestedPulsing_0.pos, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.tii, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0_NestedPulsing_0.doc, _0_MockRandom_0.tib, _0.fnm, _0_MockFixedIntBlock_0.pos, _0_nrm.cfs, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.sd, _0_Lucene41_0.doc, _0_MockRandom_0.doc, _0_MockFixedIntBlock_0.doc, _0_nrm.cfe, _0_MockRandom_0.pos, _0_MockRandom_0.tii, _0.fdx, _0.fdt, segments_3]
[junit4:junit4]   2> 14955 T976 C72 P55274 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2
[junit4:junit4]   2> 14962 T976 C72 P55274 oass.SolrIndexSearcher.<init> Opening Searcher@247cae7d main
[junit4:junit4]   2> 14962 T976 C72 P55274 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index
[junit4:junit4]   2> 14962 T976 C72 P55274 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14963 T983 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@247cae7d main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C2)}
[junit4:junit4]   2> 14964 T983 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.SyncSliceTest-1351297150721\jetty2\index
[junit4:junit4]   2> 14964 T976 C72 P55274 /update {waitSearcher=true&commit=true&commit_end_point=true&expungeDeletes=false&wt=javabin&softCommit=false&version=2} {commit=} 0 94
[junit4:junit4]   2> 14965 T965 C73 P55268 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 151
[junit4:junit4]   2> 14965 T934 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):600
[junit4:junit4]   2> 14966 T934 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:55268_solr_collection1:{"shard":"shard1","roles":null,"state":"active","core":"collection1","collection":"collection1","node_name":"127.0.0.1:55268_solr","base_url":"http://127.0.0.1:55268/solr","leader":"true"}
[junit4:junit4]   2> 14969 T963 C73 P55268 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:55274_solr_collection1:{"shard":"shard1","roles":null,"state":"active","core":"collection1","collection":"collection1","node_name":"127.0.0.1:55274_solr","base_url":"http://127.0.0.1:55274/solr"}
[junit4:junit4]   2> 14972 T977 C72 P55274 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:55268/solr/collection1lastClient and got 2 from http://127.0.0.1:55274/solr/collection1
[junit4:junit4]   2> 14975 T966 C73 P55268 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> 14978 T978 C72 P55274 REQ /select {fl=id,_version_&sort=id+asc&q=*:*&distrib=false&wt=javabin&rows=1000&version=2} hits=2 status=0 QTime=1 
[junit4:junit4]   2> ######http://127.0.0.1:55268/solr/collection1: {numFound=4,start=0,docs=[SolrDocument{id=0, _version_=1416

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

_url":"http://127.0.0.1:55454/solr"}}}},
[junit4:junit4]   1>         "control_collection":{"control_shard":{"replicas":{"127.0.0.1:55425_solr_collection1":{
[junit4:junit4]   1>                 "shard":"control_shard",
[junit4:junit4]   1>                 "roles":null,
[junit4:junit4]   1>                 "state":"active",
[junit4:junit4]   1>                 "core":"collection1",
[junit4:junit4]   1>                 "collection":"control_collection",
[junit4:junit4]   1>                 "node_name":"127.0.0.1:55425_solr",
[junit4:junit4]   1>                 "base_url":"http://127.0.0.1:55425/solr",
[junit4:junit4]   1>                 "leader":"true"}}}}}
[junit4:junit4]   1>  /zookeeper (1)
[junit4:junit4]   1>  DATA:
[junit4:junit4]   1>      
[junit4:junit4]   1> 
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=BasicDistributedZk2Test -Dtests.method=testDistribSearch -Dtests.seed=E796CC031629CEFC -Dtests.slow=true -Dtests.locale=mk -Dtests.timezone=America/Argentina/La_Rioja -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] FAILURE 32.8s | BasicDistributedZk2Test.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: Shard still reported as live in zk - 0 jetty
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([E796CC031629CEFC:6670421B6176AEC0]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(AbstractFullDistribZkTestBase.java:1231)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZk2Test.brindDownShardIndexSomeDocsAndRecover(BasicDistributedZk2Test.java:217)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:92)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at 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> 32762 T1300 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=mk, timezone=America/Argentina/La_Rioja
[junit4:junit4]   2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_07 (64-bit)/cpus=2,threads=1,free=42389208,total=268042240
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestJoin, TestJmxIntegration, TestStressRecovery, TestSurroundQueryParser, TestDefaultSimilarityFactory, BasicFunctionalityTest, TestLFUCache, SoftAutoCommitTest, TestFastWriter, DistributedQueryElevationComponentTest, DistributedSpellCheckComponentTest, TestSolrIndexConfig, TestXIncludeConfig, SpellCheckComponentTest, TestMergePolicyConfig, ZkCLITest, TestReplicationHandler, TestQuerySenderListener, BasicZkTest, SpellPossibilityIteratorTest, TestElisionMultitermQuery, TestTrie, TestSearchPerf, TestValueSourceCache, ZkSolrClientTest, TestOmitPositions, TestCodecSupport, TestPropInject, TestStressLucene, BadIndexSchemaTest, SolrRequestParserTest, TestReload, TestSolrDeletionPolicy2, QueryEqualityTest, DocumentAnalysisRequestHandlerTest, TestIBSimilarityFactory, DirectSolrSpellCheckerTest, CircularListTest, SolrPluginUtilsTest, TestIndexSearcher, TestStressVersions, DefaultValueUpdateProcessorTest, TestAnalyzedSuggestions, SignatureUpdateProcessorFactoryTest, TestQuerySenderNoQuery, NotRequiredUniqueKeyTest, ClusterStateUpdateTest, TestDistributedGrouping, CoreContainerCoreInitFailuresTest, SortByFunctionTest, SyncSliceTest, TermsComponentTest, TestRTGBase, SuggesterFSTTest, DirectUpdateHandlerTest, SolrIndexConfigTest, TestRandomFaceting, TestUtils, CurrencyFieldTest, SpatialFilterTest, MinimalSchemaTest, IndexBasedSpellCheckerTest, SampleTest, ConvertedLegacyTest, JsonLoaderTest, PeerSyncTest, TestConfig, WordBreakSolrSpellCheckerTest, MoreLikeThisHandlerTest, TermVectorComponentDistributedTest, TestLMDirichletSimilarityFactory, ClusterStateTest, MultiTermTest, BasicDistributedZk2Test]
[junit4:junit4] Completed in 32.78s, 1 test, 1 failure <<< FAILURES!

[...truncated 478 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:335: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:178: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\module-build.xml:61: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1159: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:827: There were test failures: 232 suites, 961 tests, 2 failures, 12 ignored (7 assumptions)

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



Mime
View raw message