[JENKINS] Lucene-Solr-trunk-Windows-Java6-64 - Build # 874 - Failure!

classic Classic list List threaded Threaded
1 message Options
Reply | Threaded
Open this post in threaded view
|

[JENKINS] Lucene-Solr-trunk-Windows-Java6-64 - Build # 874 - Failure!

Policeman Jenkins Server
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Windows-Java6-64/874/

1 tests failed.
REGRESSION:  org.apache.solr.cloud.LeaderElectionIntegrationTest.testSimpleSliceLeaderElection

Error Message:
We didn't find a new leader! 7000 was shutdown, but it's still showing as the leader

Stack Trace:
java.lang.AssertionError: We didn't find a new leader! 7000 was shutdown, but it's still showing as the leader
        at __randomizedtesting.SeedInfo.seed([FD65295BE419C0F3:A351E415A7665C14]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.apache.solr.cloud.LeaderElectionIntegrationTest.testSimpleSliceLeaderElection(LeaderElectionIntegrationTest.java:202)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:597)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1995)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.access$1100(RandomizedRunner.java:132)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:818)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:877)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:891)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
        at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:32)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
        at org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68)
        at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:825)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.access$700(RandomizedRunner.java:132)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$3$1.run(RandomizedRunner.java:671)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:697)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:736)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:747)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68)
        at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:38)
        at org.apache.lucene.util.TestRuleIcuHack$1.evaluate(TestRuleIcuHack.java:51)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
        at org.apache.lucene.util.TestRuleNoInstanceHooksOverrides$1.evaluate(TestRuleNoInstanceHooksOverrides.java:53)
        at org.apache.lucene.util.TestRuleNoStaticHooksShadowing$1.evaluate(TestRuleNoStaticHooksShadowing.java:52)
        at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:36)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
        at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:605)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.access$400(RandomizedRunner.java:132)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$2.run(RandomizedRunner.java:551)




Build Log:
[...truncated 6110 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.LeaderElectionIntegrationTest
[junit4:junit4] FAILURE  109s | LeaderElectionIntegrationTest.testSimpleSliceLeaderElection
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: We didn't find a new leader! 7000 was shutdown, but it's still showing as the leader
[junit4:junit4]    > at __randomizedtesting.SeedInfo.seed([FD65295BE419C0F3:A351E415A7665C14]:0)
[junit4:junit4]    > at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > at org.apache.solr.cloud.LeaderElectionIntegrationTest.testSimpleSliceLeaderElection(LeaderElectionIntegrationTest.java:202)
[junit4:junit4]    > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
[junit4:junit4]    > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4]    > at java.lang.reflect.Method.invoke(Method.java:597)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1995)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner.access$1100(RandomizedRunner.java:132)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:818)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:877)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:891)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:32)
[junit4:junit4]    > at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:825)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner.access$700(RandomizedRunner.java:132)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner$3$1.run(RandomizedRunner.java:671)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:697)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:736)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:747)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:38)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleIcuHack$1.evaluate(TestRuleIcuHack.java:51)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleNoInstanceHooksOverrides$1.evaluate(TestRuleNoInstanceHooksOverrides.java:53)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleNoStaticHooksShadowing$1.evaluate(TestRuleNoStaticHooksShadowing.java:52)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:36)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:605)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner.access$400(RandomizedRunner.java:132)
[junit4:junit4]    > at com.carrotsearch.randomizedtesting.RandomizedRunner$2.run(RandomizedRunner.java:551)
[junit4:junit4]    >
[junit4:junit4]   2> 15854 T2319 oas.SolrTestCaseJ4.setUp ###Starting testSimpleSliceLeaderElection
[junit4:junit4]   2> Creating dataDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800
[junit4:junit4]   2> 15856 T2319 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 15861 T2352 oazs.ZooKeeperServer.setTickTime tickTime set to 3000
[junit4:junit4]   2> 15864 T2352 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 15864 T2352 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 15971 T2319 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58620 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@50a67e6c
[junit4:junit4]   2> 15972 T2357 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58620
[junit4:junit4]   2> 15972 T2357 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 15972 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58623
[junit4:junit4]   2> 15972 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58623
[junit4:junit4]   2> 15973 T2355 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 15997 T2355 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0000 with negotiated timeout 10000 for client /127.0.0.1:58623
[junit4:junit4]   2> 16000 T2357 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0000, negotiated timeout = 10000
[junit4:junit4]   2> 16002 T2358 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@50a67e6c name:ZooKeeperConnection Watcher:127.0.0.1:58620 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16004 T2319 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 16023 T2356 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138b561c0da0000
[junit4:junit4]   2> 16043 T2319 oaz.ZooKeeper.close Session: 0x138b561c0da0000 closed
[junit4:junit4]   2> 16043 T2319 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58620/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@66212948
[junit4:junit4]   2> 16043 T2353 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x138b561c0da0000, likely client has closed socket
[junit4:junit4]   2> 16044 T2358 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 16047 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58623 which had sessionid 0x138b561c0da0000
[junit4:junit4]   2> 16048 T2359 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58620
[junit4:junit4]   2> 16049 T2359 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 16049 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58626
[junit4:junit4]   2> 16051 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58626
[junit4:junit4]   2> 16066 T2355 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0001 with negotiated timeout 10000 for client /127.0.0.1:58626
[junit4:junit4]   2> 16066 T2359 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0001, negotiated timeout = 10000
[junit4:junit4]   2> 16066 T2360 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@66212948 name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16066 T2319 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 16112 T2319 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 16124 T2319 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 16147 T2319 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 16170 T2319 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 16226 T2319 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 16261 T2319 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 16294 T2319 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 16317 T2319 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 16340 T2319 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 16364 T2319 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 16385 T2319 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 16408 T2319 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 16430 T2319 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 16454 T2356 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138b561c0da0001
[junit4:junit4]   2> 16473 T2319 oaz.ZooKeeper.close Session: 0x138b561c0da0001 closed
[junit4:junit4]   2> 16473 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58626 which had sessionid 0x138b561c0da0001
[junit4:junit4]   2> 16473 T2319 oasc.LeaderElectionIntegrationTest.setUp ####SETUP_START testSimpleSliceLeaderElection
[junit4:junit4]   2> 16475 T2360 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 16476 T2319 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 16476 T2319 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 16477 T2319 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 16477 T2319 oasc.CoreContainer.<init> New CoreContainer 1393515636
[junit4:junit4]   2> 16477 T2319 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 16477 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 16496 T2319 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 16515 T2319 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:58620/solr
[junit4:junit4]   2> 16515 T2319 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58620/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@157eee46
[junit4:junit4]   2> 16516 T2361 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58620
[junit4:junit4]   2> 16518 T2361 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 16518 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58629
[junit4:junit4]   2> 16518 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58629
[junit4:junit4]   2> 16540 T2355 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0002 with negotiated timeout 6000 for client /127.0.0.1:58629
[junit4:junit4]   2> 16540 T2361 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0002, negotiated timeout = 6000
[junit4:junit4]   2> 16542 T2362 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@157eee46 name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16554 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16577 T2319 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 16599 T2319 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 16600 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7000_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 16610 T2319 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7000_solr
[junit4:junit4]   2> 16636 T2319 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 16636 T2319 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 16636 T2319 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 16636 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 16636 T2319 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 16637 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 16637 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 16638 T2319 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 16638 T2319 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 16639 T2319 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 16650 T2319 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 16701 T2319 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 16723 T2319 oasc.Overseer.<init> Overseer (id=88019624185692162-127.0.0.1:7000_solr-n_0000000000) starting
[junit4:junit4]   2> 16723 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0002 type:create cxid:0x19 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16746 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16769 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16792 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16814 T2364 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 16814 T2319 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 16848 T2319 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4]   2> 16853 T2363 oasc.Overseer$CloudStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 16861 T2319 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 16862 T2319 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 16865 T2319 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 16865 T2319 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 16867 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 16868 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 16869 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 16919 T2319 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 17007 T2319 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 17010 T2319 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 17015 T2319 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 17727 T2319 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 17736 T2319 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 17740 T2319 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 17757 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17761 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17766 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17767 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17768 T2319 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7000\
[junit4:junit4]   2> 17768 T2319 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@36b60b93
[junit4:junit4]   2> 17768 T2319 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7000\index/
[junit4:junit4]   2> 17769 T2319 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7000\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 17771 T2319 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1bdbf8fd lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7a6d7e5),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 17771 T2319 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 17771 T2319 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 17772 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 17772 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 17772 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 17774 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 17775 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 17775 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 17775 T2319 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 17775 T2319 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 17776 T2319 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 17776 T2319 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 17776 T2319 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 17776 T2319 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 17776 T2319 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 17776 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 17776 T2319 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 17776 T2319 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 17777 T2319 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17777 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17777 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17778 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17778 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17778 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17779 T2319 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17779 T2319 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 17780 T2319 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 17780 T2319 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 17781 T2319 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 17784 T2319 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> 17786 T2319 oass.SolrIndexSearcher.<init> Opening Searcher@2c880550 main
[junit4:junit4]   2> 17786 T2319 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 17786 T2319 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 17786 T2319 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 17787 T2319 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 17821 T2319 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 18043 T2365 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2c880550 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 18048 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0002 type:create cxid:0x55 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 18103 T2319 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 18103 T2319 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7000/solr shardId:shard1
[junit4:junit4]   2> 18105 T2319 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 21048 T2355 oazsp.FileTxnLog.commit WARNING fsync-ing the write ahead log in SyncThread:0 took 2594ms which will adversely effect operation latency. See the ZooKeeper troubleshooting guide
[junit4:junit4]   2> 21425 T2363 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 21489 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0002 type:create cxid:0x6d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 21526 T2319 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 21646 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0002 type:create cxid:0x7c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21780 T2362 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 21840 T2319 oasc.ZkController.register We are http://127.0.0.1:7000/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 21840 T2319 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 21850 T2319 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 21854 T2319 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 21854 T2319 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 21855 T2319 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 21855 T2319 oasc.CoreContainer.<init> New CoreContainer 1535086333
[junit4:junit4]   2> 21857 T2319 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 21857 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 21879 T2319 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 21895 T2319 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:58620/solr
[junit4:junit4]   2> 21895 T2319 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58620/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@1384b2f9
[junit4:junit4]   2> 21897 T2366 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58620
[junit4:junit4]   2> 21900 T2366 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 21900 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58632
[junit4:junit4]   2> 21900 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58632
[junit4:junit4]   2> 21916 T2355 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0003 with negotiated timeout 6000 for client /127.0.0.1:58632
[junit4:junit4]   2> 21916 T2366 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0003, negotiated timeout = 6000
[junit4:junit4]   2> 21916 T2367 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1384b2f9 name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 21917 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21939 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21963 T2319 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> 21964 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7001_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> 21974 T2319 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7001_solr
[junit4:junit4]   2> 21984 T2362 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 21987 T2319 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 21987 T2319 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 21988 T2319 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 21988 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 21988 T2319 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 21990 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 21990 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 21991 T2319 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 21991 T2319 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 21993 T2319 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 22031 T2319 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4]   2> 22050 T2319 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 22051 T2319 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 22053 T2319 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 22053 T2319 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 22057 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 22058 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 22058 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 22115 T2319 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 22196 T2319 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 22199 T2319 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 22208 T2319 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 22385 T2363 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 22446 T2362 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 22446 T2367 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 22946 T2319 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 22956 T2319 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 22961 T2319 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 22986 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 22991 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 22995 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 22997 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 23000 T2319 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7001\
[junit4:junit4]   2> 23000 T2319 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@36b60b93
[junit4:junit4]   2> 23000 T2319 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7001\index/
[junit4:junit4]   2> 23001 T2319 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7001\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 23007 T2319 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> commit{dir=MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\index7227372275tmp lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4dffb848),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 23008 T2319 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 23008 T2319 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 23011 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 23011 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 23012 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 23013 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 23014 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 23014 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 23015 T2319 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 23015 T2319 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 23015 T2319 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 23015 T2319 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 23017 T2319 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 23017 T2319 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 23017 T2319 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 23017 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 23017 T2319 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 23018 T2319 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 23018 T2319 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 23018 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 23018 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 23019 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 23019 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 23019 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 23019 T2319 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 23021 T2319 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 23021 T2319 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 23023 T2319 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 23025 T2319 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 23028 T2319 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> 23034 T2319 oass.SolrIndexSearcher.<init> Opening Searcher@6d217978 main
[junit4:junit4]   2> 23034 T2319 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 23035 T2319 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 23036 T2319 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 23036 T2319 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 23081 T2319 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 23327 T2368 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6d217978 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 23355 T2319 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 23355 T2319 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7001/solr shardId:shard1
[junit4:junit4]   2> 23385 T2319 oasc.ZkController.register We are http://127.0.0.1:7001/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 23385 T2319 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 23396 T2319 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 23397 T2319 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 23397 T2319 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 23399 T2319 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 23399 T2319 oasc.CoreContainer.<init> New CoreContainer 41581215
[junit4:junit4]   2> 23399 T2319 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 23399 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 23421 T2319 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 23440 T2319 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:58620/solr
[junit4:junit4]   2> 23441 T2319 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58620/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@37085dfe
[junit4:junit4]   2> 23442 T2369 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58620
[junit4:junit4]   2> 23445 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58635
[junit4:junit4]   2> 23445 T2369 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 23446 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58635
[junit4:junit4]   2> 23467 T2355 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0004 with negotiated timeout 6000 for client /127.0.0.1:58635
[junit4:junit4]   2> 23467 T2369 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0004, negotiated timeout = 6000
[junit4:junit4]   2> 23467 T2370 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@37085dfe name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 23468 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 23495 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 23519 T2319 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7002_solr
[junit4:junit4]   2> 23524 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7002_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7002_solr
[junit4:junit4]   2> 23536 T2319 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7002_solr
[junit4:junit4]   2> 23554 T2362 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 23556 T2367 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 23560 T2319 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 23560 T2319 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 23560 T2319 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 23560 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 23562 T2319 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 23562 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 23562 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 23563 T2319 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 23563 T2319 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 23564 T2319 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 23603 T2319 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4]   2> 23622 T2319 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 23622 T2319 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 23626 T2319 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 23626 T2319 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 23630 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 23630 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 23631 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 23674 T2319 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 23682 T2363 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 23790 T2370 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 23790 T2367 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 23792 T2362 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 23800 T2319 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 23811 T2319 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 23818 T2319 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 24625 T2319 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 24636 T2319 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 24639 T2319 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 24661 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 24666 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 24671 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 24674 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 24676 T2319 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7002\
[junit4:junit4]   2> 24676 T2319 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@36b60b93
[junit4:junit4]   2> 24677 T2319 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7002\index/
[junit4:junit4]   2> 24678 T2319 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7002\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 24680 T2319 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3983fc21 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@678697ba),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 24680 T2319 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 24680 T2319 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 24681 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 24681 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 24682 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 24683 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 24684 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 24684 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 24684 T2319 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 24684 T2319 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 24684 T2319 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 24686 T2319 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 24686 T2319 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 24686 T2319 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 24686 T2319 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 24686 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 24687 T2319 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 24688 T2319 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 24688 T2319 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 24688 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 24688 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 24689 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 24689 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 24689 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 24689 T2319 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 24689 T2319 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 24691 T2319 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 24691 T2319 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 24694 T2319 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 24695 T2319 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> 24699 T2319 oass.SolrIndexSearcher.<init> Opening Searcher@1dd2967a main
[junit4:junit4]   2> 24699 T2319 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 24700 T2319 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 24700 T2319 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 24700 T2319 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 24732 T2319 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 24991 T2371 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1dd2967a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 25019 T2319 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 25019 T2319 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7002/solr shardId:shard1
[junit4:junit4]   2> 28166 T2366 oaz.ClientCnxn$SendThread.run Client session timed out, have not heard from server in 4372ms for sessionid 0x138b561c0da0003, closing socket connection and attempting reconnect
[junit4:junit4]   2> 28167 T2353 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x138b561c0da0003, likely client has closed socket
[junit4:junit4]   2> 28170 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58632 which had sessionid 0x138b561c0da0003
[junit4:junit4]   2> 28291 T2367 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1384b2f9 name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 28681 T2361 oaz.ClientCnxn$SendThread.run Client session timed out, have not heard from server in 4219ms for sessionid 0x138b561c0da0002, closing socket connection and attempting reconnect
[junit4:junit4]   2> 28681 T2353 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x138b561c0da0002, likely client has closed socket
[junit4:junit4]   2> 28681 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58629 which had sessionid 0x138b561c0da0002
[junit4:junit4]   2> 28806 T2362 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@157eee46 name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 29396 T2369 oaz.ClientCnxn$SendThread.run Client session timed out, have not heard from server in 4373ms for sessionid 0x138b561c0da0004, closing socket connection and attempting reconnect
[junit4:junit4]   2> 29396 T2353 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x138b561c0da0004, likely client has closed socket
[junit4:junit4]   2> 29396 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58635 which had sessionid 0x138b561c0da0004
[junit4:junit4]   2> 29521 T2370 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@37085dfe name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:Disconnected type:None path:null path:null type:None
[junit4:junit4]   2> 29829 T2355 oazsp.FileTxnLog.commit WARNING fsync-ing the write ahead log in SyncThread:0 took 3864ms which will adversely effect operation latency. See the ZooKeeper troubleshooting guide
[junit4:junit4]   2> 29829 T2355 oazs.NIOServerCnxn.sendBuffer SEVERE Unexpected Exception:  java.nio.channels.CancelledKeyException
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:55)
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.interestOps(SelectionKeyImpl.java:59)
[junit4:junit4]   2> at org.apache.zookeeper.server.NIOServerCnxn.sendBuffer(NIOServerCnxn.java:418)
[junit4:junit4]   2> at org.apache.zookeeper.server.NIOServerCnxn.sendResponse(NIOServerCnxn.java:1509)
[junit4:junit4]   2> at org.apache.zookeeper.server.FinalRequestProcessor.processRequest(FinalRequestProcessor.java:359)
[junit4:junit4]   2> at org.apache.zookeeper.server.SyncRequestProcessor.flush(SyncRequestProcessor.java:163)
[junit4:junit4]   2> at org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:98)
[junit4:junit4]   2>
[junit4:junit4]   2> 29830 T2355 oazs.NIOServerCnxn.sendBuffer SEVERE Unexpected Exception:  java.nio.channels.CancelledKeyException
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:55)
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.interestOps(SelectionKeyImpl.java:59)
[junit4:junit4]   2> at org.apache.zookeeper.server.NIOServerCnxn.sendBuffer(NIOServerCnxn.java:418)
[junit4:junit4]   2> at org.apache.zookeeper.server.NIOServerCnxn.sendResponse(NIOServerCnxn.java:1509)
[junit4:junit4]   2> at org.apache.zookeeper.server.FinalRequestProcessor.processRequest(FinalRequestProcessor.java:359)
[junit4:junit4]   2> at org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:135)
[junit4:junit4]   2>
[junit4:junit4]   2> 29830 T2355 oazs.NIOServerCnxn.sendBuffer SEVERE Unexpected Exception:  java.nio.channels.CancelledKeyException
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:55)
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.interestOps(SelectionKeyImpl.java:59)
[junit4:junit4]   2> at org.apache.zookeeper.server.NIOServerCnxn.sendBuffer(NIOServerCnxn.java:418)
[junit4:junit4]   2> at org.apache.zookeeper.server.NIOServerCnxn.sendResponse(NIOServerCnxn.java:1509)
[junit4:junit4]   2> at org.apache.zookeeper.server.FinalRequestProcessor.processRequest(FinalRequestProcessor.java:162)
[junit4:junit4]   2> at org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:135)
[junit4:junit4]   2>
[junit4:junit4]   2> 29830 T2355 oazs.NIOServerCnxn.sendBuffer SEVERE Unexpected Exception:  java.nio.channels.CancelledKeyException
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:55)
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.interestOps(SelectionKeyImpl.java:59)
[junit4:junit4]   2> at org.apache.zookeeper.server.NIOServerCnxn.sendBuffer(NIOServerCnxn.java:418)
[junit4:junit4]   2> at org.apache.zookeeper.server.NIOServerCnxn.sendResponse(NIOServerCnxn.java:1509)
[junit4:junit4]   2> at org.apache.zookeeper.server.FinalRequestProcessor.processRequest(FinalRequestProcessor.java:162)
[junit4:junit4]   2> at org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:135)
[junit4:junit4]   2>
[junit4:junit4]   2> 29830 T2355 oazs.NIOServerCnxn.sendBuffer SEVERE Unexpected Exception:  java.nio.channels.CancelledKeyException
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:55)
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.interestOps(SelectionKeyImpl.java:59)
[junit4:junit4]   2> at org.apache.zookeeper.server.NIOServerCnxn.sendBuffer(NIOServerCnxn.java:418)
[junit4:junit4]   2> at org.apache.zookeeper.server.NIOServerCnxn.sendResponse(NIOServerCnxn.java:1509)
[junit4:junit4]   2> at org.apache.zookeeper.server.FinalRequestProcessor.processRequest(FinalRequestProcessor.java:162)
[junit4:junit4]   2> at org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:135)
[junit4:junit4]   2>
[junit4:junit4]   2> 30516 T2361 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:58620
[junit4:junit4]   2> 30516 T2361 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 30516 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58636
[junit4:junit4]   2> 30516 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to renew session 0x138b561c0da0002 at /127.0.0.1:58636
[junit4:junit4]   2> 30518 T2353 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0002 with negotiated timeout 6000 for client /127.0.0.1:58636
[junit4:junit4]   2> 30518 T2361 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0002, negotiated timeout = 6000
[junit4:junit4]   2> 30519 T2362 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@157eee46 name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 30525 T2363 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 30599 T2362 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 30707 T2366 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:58620
[junit4:junit4]   2> 30707 T2366 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 30707 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58637
[junit4:junit4]   2> 30709 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to renew session 0x138b561c0da0003 at /127.0.0.1:58637
[junit4:junit4]   2> 30710 T2353 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0003 with negotiated timeout 6000 for client /127.0.0.1:58637
[junit4:junit4]   2> 30710 T2366 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0003, negotiated timeout = 6000
[junit4:junit4]   2> 30711 T2367 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1384b2f9 name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 30711 T2367 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 31511 T2369 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server 127.0.0.1/127.0.0.1:58620
[junit4:junit4]   2> 31511 T2369 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 31511 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58638
[junit4:junit4]   2> 31513 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to renew session 0x138b561c0da0004 at /127.0.0.1:58638
[junit4:junit4]   2> 31514 T2353 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0004 with negotiated timeout 6000 for client /127.0.0.1:58638
[junit4:junit4]   2> 31514 T2369 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0004, negotiated timeout = 6000
[junit4:junit4]   2> 31514 T2370 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@37085dfe name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 31515 T2370 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 31538 T2319 oasc.ZkController.register We are http://127.0.0.1:7002/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 31538 T2319 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 31559 T2319 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 31563 T2319 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 31564 T2319 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 31564 T2319 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 31565 T2319 oasc.CoreContainer.<init> New CoreContainer 1756149052
[junit4:junit4]   2> 31565 T2319 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 31566 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 31589 T2319 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 31608 T2319 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:58620/solr
[junit4:junit4]   2> 31609 T2319 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58620/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@22aeabd4
[junit4:junit4]   2> 31610 T2372 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58620
[junit4:junit4]   2> 31612 T2372 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 31612 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58641
[junit4:junit4]   2> 31612 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58641
[junit4:junit4]   2> 31632 T2355 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0005 with negotiated timeout 6000 for client /127.0.0.1:58641
[junit4:junit4]   2> 31632 T2372 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0005, negotiated timeout = 6000
[junit4:junit4]   2> 31633 T2373 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@22aeabd4 name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 31633 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 31660 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 31684 T2319 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7003_solr
[junit4:junit4]   2> 31685 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7003_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7003_solr
[junit4:junit4]   2> 31707 T2319 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7003_solr
[junit4:junit4]   2> 31730 T2367 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 31732 T2362 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 31732 T2370 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 31736 T2319 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 31736 T2319 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 31738 T2319 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 31738 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 31739 T2319 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 31739 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 31739 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 31740 T2319 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 31740 T2319 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 31741 T2319 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 31786 T2319 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4]   2> 31805 T2319 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 31806 T2319 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 31808 T2319 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 31808 T2319 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 31812 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 31813 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 31814 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 31869 T2319 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 31882 T2363 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 31953 T2367 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 31954 T2362 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 31955 T2373 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 31955 T2370 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 31963 T2319 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 31980 T2319 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 31986 T2319 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 32773 T2319 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 32783 T2319 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 32789 T2319 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 32817 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 32828 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 32834 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 32837 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 32839 T2319 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7003\
[junit4:junit4]   2> 32840 T2319 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@36b60b93
[junit4:junit4]   2> 32840 T2319 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7003\index/
[junit4:junit4]   2> 32840 T2319 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7003\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 32841 T2319 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@355a8e36 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6a0ba94),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 32841 T2319 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 32842 T2319 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 32844 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 32844 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 32844 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 32845 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 32845 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 32845 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 32845 T2319 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 32845 T2319 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 32845 T2319 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 32846 T2319 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 32847 T2319 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 32847 T2319 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 32847 T2319 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 32847 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 32847 T2319 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 32848 T2319 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 32848 T2319 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 32848 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 32850 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 32850 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 32851 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 32851 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 32851 T2319 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 32852 T2319 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 32852 T2319 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 32852 T2319 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 32856 T2319 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 32857 T2319 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> 32861 T2319 oass.SolrIndexSearcher.<init> Opening Searcher@4d4bc12d main
[junit4:junit4]   2> 32861 T2319 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 32861 T2319 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 32861 T2319 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 32862 T2319 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 32914 T2319 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 33222 T2374 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4d4bc12d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 33250 T2319 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 33250 T2319 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7003/solr shardId:shard1
[junit4:junit4]   2> 33275 T2363 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 33280 T2319 oasc.ZkController.register We are http://127.0.0.1:7003/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 33281 T2319 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 33350 T2319 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 33353 T2319 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 33354 T2319 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 33354 T2319 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 33354 T2319 oasc.CoreContainer.<init> New CoreContainer 1116973420
[junit4:junit4]   2> 33354 T2319 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 33354 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 33365 T2319 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 33382 T2319 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:58620/solr
[junit4:junit4]   2> 33384 T2319 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58620/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@6c581399
[junit4:junit4]   2> 33387 T2375 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58620
[junit4:junit4]   2> 33389 T2375 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 33389 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58644
[junit4:junit4]   2> 33389 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58644
[junit4:junit4]   2> 33437 T2355 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0006 with negotiated timeout 6000 for client /127.0.0.1:58644
[junit4:junit4]   2> 33437 T2375 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0006, negotiated timeout = 6000
[junit4:junit4]   2> 33437 T2376 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6c581399 name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 33437 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 33488 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 33514 T2362 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 33515 T2367 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 33515 T2370 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 33515 T2373 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 33538 T2319 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:7004_solr
[junit4:junit4]   2> 33539 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:7004_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:7004_solr
[junit4:junit4]   2> 33559 T2319 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:7004_solr
[junit4:junit4]   2> 33610 T2367 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 33610 T2370 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 33611 T2373 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 33638 T2319 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 33638 T2362 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 33638 T2319 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 33638 T2319 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 33639 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 33639 T2319 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 33640 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 33640 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 33640 T2319 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 33643 T2319 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 33643 T2319 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 33689 T2319 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4]   2> 33710 T2319 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 33710 T2319 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 33712 T2319 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 33712 T2319 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 33717 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 33718 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 33718 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 33780 T2319 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 33873 T2319 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 33874 T2319 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 33880 T2319 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 34643 T2319 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 34651 T2319 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 34656 T2319 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 34683 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 34689 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 34693 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 34695 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 34697 T2319 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7004\
[junit4:junit4]   2> 34697 T2319 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@36b60b93
[junit4:junit4]   2> 34699 T2319 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7004\index/
[junit4:junit4]   2> 34699 T2319 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_7004\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 34700 T2319 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6e8c1c8a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30a5e0b9),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 34700 T2319 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 34700 T2319 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 34702 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 34702 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 34702 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 34704 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 34704 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 34704 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 34704 T2319 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 34704 T2319 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 34704 T2319 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 34705 T2319 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 34706 T2319 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 34706 T2319 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 34706 T2319 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 34706 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 34706 T2319 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 34707 T2319 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 34707 T2319 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 34707 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 34707 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 34707 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 34707 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 34708 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 34708 T2319 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 34710 T2319 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 34710 T2319 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 34711 T2319 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 34713 T2319 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 34714 T2319 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> 34718 T2319 oass.SolrIndexSearcher.<init> Opening Searcher@2d2a70b7 main
[junit4:junit4]   2> 34718 T2319 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.BaseDirectoryWrapper
[junit4:junit4]   2> 34719 T2319 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 34719 T2319 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 34721 T2319 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 34781 T2319 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 35075 T2377 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2d2a70b7 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 35103 T2319 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 35103 T2319 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:7004/solr shardId:shard1
[junit4:junit4]   2> 35133 T2319 oasc.ZkController.register We are http://127.0.0.1:7004/solr/collection1/ and leader is http://127.0.0.1:7000/solr/collection1/
[junit4:junit4]   2> 35133 T2319 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 35155 T2319 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 35157 T2319 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 35159 T2319 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr
[junit4:junit4]   2> 35159 T2319 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\solr.xml
[junit4:junit4]   2> 35160 T2319 oasc.CoreContainer.<init> New CoreContainer 748160780
[junit4:junit4]   2> 35160 T2319 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 35161 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\'
[junit4:junit4]   2> 35186 T2319 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 35210 T2319 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:58620/solr
[junit4:junit4]   2> 35211 T2319 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58620/solr sessionTimeout=3000 watcher=org.apache.solr.common.cloud.ConnectionManager@30f6bbb3
[junit4:junit4]   2> 35212 T2378 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58620
[junit4:junit4]   2> 35213 T2378 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 35213 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58647
[junit4:junit4]   2> 35214 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58647
[junit4:junit4]   2> 35239 T2355 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0007 with negotiated timeout 6000 for client /127.0.0.1:58647
[junit4:junit4]   2> 35239 T2378 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0007, negotiated timeout = 6000
[junit4:junit4]   2> 35239 T2379 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@30f6bbb3 name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 35240 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0007 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 35266 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0007 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 35291 T2319 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:3333_solr
[junit4:junit4]   2> 35292 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0007 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:3333_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:3333_solr
[junit4:junit4]   2> 35314 T2319 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:3333_solr
[junit4:junit4]   2> 35340 T2362 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 35340 T2370 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 35341 T2367 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 35341 T2373 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 35342 T2376 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 35349 T2319 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 35349 T2319 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 35349 T2319 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 35350 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 35350 T2319 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 35350 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 35350 T2319 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 35352 T2319 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 35352 T2319 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 35352 T2319 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 35400 T2319 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4]   2> 35422 T2319 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1
[junit4:junit4]   2> 35422 T2319 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 35425 T2319 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 35425 T2319 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 35431 T2319 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\'
[junit4:junit4]   2> 35431 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 35432 T2319 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Jenkins/workspace/Lucene-Solr-trunk-Windows-Java6-64/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 35757 T2363 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 35773 T2319 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 35884 T2319 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 35887 T2362 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 35887 T2370 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 35887 T2376 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 35887 T2367 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 35888 T2373 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 35888 T2379 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 35889 T2319 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 35896 T2319 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 36730 T2319 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 36739 T2319 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 36745 T2319 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 36782 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 36791 T2319 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 36795 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 36798 T2319 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 36799 T2319 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_3333\
[junit4:junit4]   2> 36799 T2319 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@36b60b93
[junit4:junit4]   2> 36801 T2319 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_3333\index/
[junit4:junit4]   2> 36802 T2319 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory 'C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build\solr-core\test\J0\.\solrtest-LeaderElectionIntegrationTest-1343072878800\data_3333\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 36803 T2319 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4787ce94 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14c7b324),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 36803 T2319 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 36803 T2319 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 36806 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 36806 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 36806 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 36807 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 36807 T2319 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 36807 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 36809 T2319 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 36809 T2319 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 36809 T2319 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 36809 T2319 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 36809 T2319 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 36810 T2319 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 36810 T2319 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 36810 T2319 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 36810 T2319 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 36810 T2319 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 36810 T2319 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 36811 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 36811 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 36811 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 36813 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 36813 T2319 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 36813 T2319 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 36814 T2319 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 36815 T2319 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 36815 T2319 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 36818 T2319 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 36821 T2319 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> 36823 T2319 oass.SolrIndexSearcher.<init> Opening Searcher@70dde4fe main
[junit4:junit4]   2> 36823 T2319 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 36823 T2319 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 36825 T2319 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 36825 T2319 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 36888 T2319 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 37183 T2380 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@70dde4fe main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 37216 T2319 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 37216 T2319 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:3333/solr shardId:shard2
[junit4:junit4]   2> 37218 T2319 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 37337 T2319 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 37345 T2363 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 37368 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0007 type:create cxid:0x48 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 37598 T2362 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 37600 T2370 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 37600 T2376 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 37600 T2379 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 37600 T2367 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 37601 T2373 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 37614 T2319 oasc.ZkController.register We are http://127.0.0.1:3333/solr/collection1/ and leader is http://127.0.0.1:3333/solr/collection1/
[junit4:junit4]   2> 37614 T2319 oasc.ZkController.checkRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 37668 T2319 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 37670 T2319 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:58620/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@4b8ae67a
[junit4:junit4]   2> 37673 T2381 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:58620
[junit4:junit4]   2> 37675 T2381 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:58620, initiating session
[junit4:junit4]   2> 37675 T2353 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:58650
[junit4:junit4]   2> 37675 T2353 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:58650
[junit4:junit4]   2> 37709 T2381 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server 127.0.0.1/127.0.0.1:58620, sessionid = 0x138b561c0da0008, negotiated timeout = 10000
[junit4:junit4]   2> 37709 T2355 oazs.NIOServerCnxn.finishSessionInit Established session 0x138b561c0da0008 with negotiated timeout 10000 for client /127.0.0.1:58650
[junit4:junit4]   2> 37709 T2382 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4b8ae67a name:ZooKeeperConnection Watcher:127.0.0.1:58620/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 37715 T2319 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4]   2> 37744 T2319 oasc.LeaderElectionIntegrationTest.setUp ####SETUP_END testSimpleSliceLeaderElection
[junit4:junit4]   2> 37744 T2319 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1393515636
[junit4:junit4]   2> 37745 T2319 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@104cb477
[junit4:junit4]   2> 37751 T2319 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 37751 T2319 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 37753 T2356 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138b561c0da0002
[junit4:junit4]   2> 37778 T2362 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37779 T2370 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37779 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58636 which had sessionid 0x138b561c0da0002
[junit4:junit4]   2> 37779 T2376 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37779 T2382 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37779 T2379 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37782 T2319 oaz.ZooKeeper.close Session: 0x138b561c0da0002 closed
[junit4:junit4]   2> 37783 T2373 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37784 T2367 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:7001/solr/collection1/
[junit4:junit4]   2> 37786 T2367 oasc.SyncStrategy.sync SEVERE No UpdateLog found - cannot sync
[junit4:junit4]   2> 37787 T2367 oasc.ShardLeaderElectionContext.rejoinLeaderElection There is a better leader candidate than us - going back into recovery
[junit4:junit4]   2> 37846 T2367 oasu.DefaultSolrCoreState.doRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 37878 T2367 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 37915 T2367 oasc.Overseer.<init> Overseer (id=88019624185692163-127.0.0.1:7001_solr-n_0000000001) starting
[junit4:junit4]   2> 37917 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0003 type:create cxid:0x54 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 37942 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0003 type:create cxid:0x55 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 37970 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0003 type:create cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 37984 T2356 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x138b561c0da0003 type:create cxid:0x57 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 38009 T2367 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 38009 T2384 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 38014 T2383 oasc.Overseer$CloudStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 38024 T2383 oascc.ZkStateReader.updateCloudState Updating cloud state from ZooKeeper...
[junit4:junit4]   2> 38146 T2379 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 38146 T2373 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 38147 T2367 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 38147 T2370 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 38148 T2376 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 38148 T2382 oascc.ZkStateReader$2.process A cluster state change has occurred
[junit4:junit4]   2> 38423 T2363 oasc.Overseer$CloudStateUpdater.amILeader WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
[junit4:junit4]   2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
[junit4:junit4]   2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:187)
[junit4:junit4]   2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:113)
[junit4:junit4]   2> at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   2>
[junit4:junit4]   2> 38424 T2363 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=88019624185692162-127.0.0.1:7000_solr-n_0000000000) am no longer a leader.
[junit4:junit4]   2> 39149 T2362 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 39149 T2362 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   1> / (6)
[junit4:junit4]   1>  /configs (1)
[junit4:junit4]   1>   /configs/conf1 (9)
[junit4:junit4]   1>    /configs/conf1/mapping-ISOLatin1Accent.txt (0)
[junit4:junit4]   1>    DATA: ...supressed...
[junit4:junit4]   1>    /configs/conf1/currency.xml (0)
[junit4:junit4]   1>    DATA: ...supressed...
[junit4:junit4]   1>    /configs/conf1/protwords.txt (0)
[junit4:junit4]   1>    DATA: ...supressed...
[junit4:junit4]   1>    /configs/conf1/synonyms.txt (0)
[junit4:junit4]   1>    DATA: ...supressed...
[junit4:junit4]   1>    /configs/conf1/old_synonyms.txt (0)
[junit4:junit4]   1>    DATA: ...supressed...
[junit4:junit4]   1>    /configs/conf1/solrconfig.xml (0)
[junit4:junit4]   1>    DATA: ...supressed...
[junit4:junit4]   1>    /configs/conf1/stopwords.txt (0)
[junit4:junit4]   1>    DATA: ...supressed...
[junit4:junit4]   1>    /configs/conf1/open-exchange-rates.json (0)
[junit4:junit4]   1>    DATA:
[junit4:junit4]   1>        {
[junit4:junit4]   1>          "disclaimer": "This data is collected from various providers and provided free of charge for informational purposes only, with no guarantee whatsoever of accuracy, validity, availability or fitness for any purpose; use at your own risk. Other than that - have fun, and please share/watch/fork if you think data like this should be free!",
[junit4:junit4]   1>          "license": "Data collected from various providers with public-facing APIs; copyright may apply; not for resale; no warranties given.",
[junit4:junit4]   1>          "timestamp": 1332070464,
[junit4:junit4]   1>          "base": "USD",
[junit4:junit4]   1>          "rates": {
[junit4:junit4]   1>            "AED": 3.6732,
[junit4:junit4]   1>            "AFN": 48.299999,
[junit4:junit4]   1>            "ALL": 105.919998,
[junit4:junit4]   1>            "AMD": 388.890015,
[junit4:junit4]   1>            "ANG": 1.79,
[junit4:junit4]   1>            "AOA": 94.769997,
[junit4:junit4]   1>            "ARS": 4.35,
[junit4:junit4]   1>            "AUD": 0.943931,
[junit4:junit4]   1>            "AWG": 1.7899,
[junit4:junit4]   1>            "AZN": 0.7863,
[junit4:junit4]   1>            "BAM": 1.48775,
[junit4:junit4]   1>            "BBD": 2,
[junit4:junit4]   1>            "BDT": 82,
[junit4:junit4]   1>            "BGN": 1.4962,
[junit4:junit4]   1>            "BHD": 0.37703,
[junit4:junit4]   1>            "BIF": 1304.170044,
[junit4:junit4]   1>            "BMD": 1,
[junit4:junit4]   1>            "BND": 1.2575,
[junit4:junit4]   1>            "BOB": 6.91,
[junit4:junit4]   1>            "BRL": 1.8003,
[junit4:junit4]   1>            "BSD": 1,
[junit4:junit4]   1>            "BTN": 50.185001,
[junit4:junit4]   1>            "BWP": 7.2307,
[junit4:junit4]   1>            "BYR": 8150,
[junit4:junit4]   1>            "BZD": 1.9135,
[junit4:junit4]   1>            "CAD": 0.9921,
[junit4:junit4]   1>            "CDF": 917.276917,
[junit4:junit4]   1>            "CHF": 0.9164,
[junit4:junit4]   1>            "CLF": 0.02146,
[junit4:junit4]   1>            "CLP": 482.75,
[junit4:junit4]   1>            "CNY": 6.3239,
[junit4:junit4]   1>            "COP": 1760,
[junit4:junit4]   1>            "CRC": 507.600006,
[junit4:junit4]   1>            "CUP": 1,
[junit4:junit4]   1>            "CVE": 84.190002,
[junit4:junit4]   1>            "CZK": 18.606001,
[junit4:junit4]   1>            "DJF": 179.490005,
[junit4:junit4]   1>            "DKK": 5.64424,
[junit4:junit4]   1>            "DOP": 39.025002,
[junit4:junit4]   1>            "DZD": 74.544998,
[junit4:junit4]   1>            "EGP": 6.0385,
[junit4:junit4]   1>            "ETB": 17.720449,
[junit4:junit4]   1>            "EUR": 0.758956,
[junit4:junit4]   1>            "FJD": 1.7734,
[junit4:junit4]   1>            "FKP": 0.6316,
[junit4:junit4]   1>            "GBP": 0.631373,
[junit4:junit4]   1>            "GEL": 1.6469,
[junit4:junit4]   1>            "GHS": 1.7455,
[junit4:junit4]   1>            "GIP": 0.63165,
[junit4:junit4]   1>            "GMD": 31.5,
[junit4:junit4]   1>            "GNF": 7100,
[junit4:junit4]   1>            "GTQ": 7.6975,
[junit4:junit4]   1>            "GYD": 203.699997,
[junit4:junit4]   1>            "HKD": 7.76306,
[junit4:junit4]   1>            "HNL": 19.055,
[junit4:junit4]   1>            "HRK": 5.7333,
[junit4:junit4]   1>            "HTG": 41,
[junit4:junit4]   1>            "HUF": 219.850006,
[junit4:junit4]   1>            "IDR": 9118,
[junit4:junit4]   1>            "IEP": 0.5978,
[junit4:junit4]   1>            "ILS": 3.7542,
[junit4:junit4]   1>            "INR": 50.165001,
[junit4:junit4]   1>            "IQD": 1165.5,
[junit4:junit4]   1>            "IRR": 12308,
[junit4:junit4]   1>            "ISK": 127.440002,
[junit4:junit4]   1>            "JMD": 86.699997,
[junit4:junit4]   1>            "JOD": 0.7095,
[junit4:junit4]   1>            "JPY": 83.445,
[junit4:junit4]   1>            "KES": 83.18,
[junit4:junit4]   1>            "KGS": 46.699402,
[junit4:junit4]   1>            "KHR": 4010.300049,
[junit4:junit4]   1>            "KMF": 373.424255,
[junit4:junit4]   1>            "KPW": 900,
[junit4:junit4]   1>            "KRW": 1125.849976,
[junit4:junit4]   1>            "KWD": 0.27925,
[junit4:junit4]   1>            "KZT": 147.690002,
[junit4:junit4]   1>            "LAK": 7993.799805,
[junit4:junit4]   1>            "LBP": 1504,
[junit4:junit4]   1>            "LKR": 125.224998,
[junit4:junit4]   1>            "LRD": 73.459999,
[junit4:junit4]   1>            "LSL": 7.5768,
[junit4:junit4]   1>            "LTL": 2.6219,
[junit4:junit4]   1>            "LVL": 0.5291,
[junit4:junit4]   1>            "LYD": 1.2572,
[junit4:junit4]   1>            "MAD": 8.4611,
[junit4:junit4]   1>            "MDL": 11.89,
[junit4:junit4]   1>            "MGA": 2155,
[junit4:junit4]   1>            "MKD": 46.705002,
[junit4:junit4]   1>            "MMK": 6.51,
[junit4:junit4]   1>            "MNT": 1322.5,
[junit4:junit4]   1>            "MOP": 7.9958,
[junit4:junit4]   1>            "MRO": 293,
[junit4:junit4]   1>            "MUR": 29.110001,
[junit4:junit4]   1>            "MVR": 15.36,
[junit4:junit4]   1>            "MWK": 165.206207,
[junit4:junit4]   1>            "MXN": 12.6745,
[junit4:junit4]   1>            "MYR": 3.0575,
[junit4:junit4]   1>            "MZN": 27.200001,
[junit4:junit4]   1>            "NAD": 7.58,
[junit4:junit4]   1>            "NGN": 157.600006,
[junit4:junit4]   1>            "NIO": 23.215,
[junit4:junit4]   1>            "NOK": 5.73163,
[junit4:junit4]   1>            "NPR": 80.620003,
[junit4:junit4]   1>            "NZD": 1.212269,
[junit4:junit4]   1>            "OMR": 0.38485,
[junit4:junit4]   1>            "PAB": 1,
[junit4:junit4]   1>            "PEN": 2.674,
[junit4:junit4]   1>            "PGK": 2.0627,
[junit4:junit4]   1>            "PHP": 43.02,
[junit4:junit4]   1>            "PKR": 90.800003,
[junit4:junit4]   1>            "PLN": 3.1285,
[junit4:junit4]   1>            "PYG": 4245,
[junit4:junit4]   1>            "QAR": 3.6415,
[junit4:junit4]   1>            "RON": 3.3256,
[junit4:junit4]   1>            "RSD": 84.100502,
[junit4:junit4]   1>            "RUB": 29.2342,
[junit4:junit4]   1>            "RWF": 606.717468,
[junit4:junit4]   1>            "SAR": 3.7505,
[junit4:junit4]   1>            "SBD": 7.075973,
[junit4:junit4]   1>            "SCR": 14.0447,
[junit4:junit4]   1>            "SDG": 2.6765,
[junit4:junit4]   1>            "SEK": 6.74525,
[junit4:junit4]   1>            "SGD": 1.258,
[junit4:junit4]   1>            "SHP": 0.63165,
[junit4:junit4]   1>            "SLL": 4364.5,
[junit4:junit4]   1>            "SOS": 1629,
[junit4:junit4]   1>            "SRD": 3.2875,
[junit4:junit4]   1>            "STD": 18650,
[junit4:junit4]   1>            "SVC": 8.7475,
[junit4:junit4]   1>            "SYP": 57.450001,
[junit4:junit4]   1>            "SZL": 7.5752,
[junit4:junit4]   1>            "THB": 30.700001,
[junit4:junit4]   1>            "TJS": 4.7588,
[junit4:junit4]   1>            "TMT": 2.85,
[junit4:junit4]   1>            "TND": 1.5178,
[junit4:junit4]   1>            "TOP": 1.693601,
[junit4:junit4]   1>            "TRY": 1.796,
[junit4:junit4]   1>            "TTD": 6.40015,
[junit4:junit4]   1>            "TWD": 29.532,
[junit4:junit4]   1>            "TZS": 1595,
[junit4:junit4]   1>            "UAH": 8.029,
[junit4:junit4]   1>            "UGX": 2481.699951,
[junit4:junit4]   1>            "USD": 1,
[junit4:junit4]   1>            "UYU": 19.469999,
[junit4:junit4]   1>            "UZS": 1835.75,
[junit4:junit4]   1>            "VEF": 4.295,
[junit4:junit4]   1>            "VND": 20820,
[junit4:junit4]   1>            "VUV": 90.199997,
[junit4:junit4]   1>            "WST": 2.247475,
[junit4:junit4]   1>            "XAF": 497.898987,
[junit4:junit4]   1>            "XCD": 2.7,
[junit4:junit4]   1>            "XDR": 0.652794,
[junit4:junit4]   1>            "XOF": 498.399994,
[junit4:junit4]   1>            "XPF": 90.639999,
[junit4:junit4]   1>            "YER": 216.005005,
[junit4:junit4]   1>            "ZAR": 7.5688,
[junit4:junit4]   1>            "ZMK": 5271.5,
[junit4:junit4]   1>            "ZWD": 378.700012,
[junit4:junit4]   1>            "ZWL": 322.355011
[junit4:junit4]   1>          }
[junit4:junit4]   1>        }
[junit4:junit4]   1>    /configs/conf1/schema.xml (0)
[junit4:junit4]   1>    DATA: ...supressed...
[junit4:junit4]   1>  /live_nodes (5)
[junit4:junit4]   1>   /live_nodes/127.0.0.1:7001_solr (0)
[junit4:junit4]   1>   /live_nodes/127.0.0.1:3333_solr (0)
[junit4:junit4]   1>   /live_nodes/127.0.0.1:7004_solr (0)
[junit4:junit4]   1>   /live_nodes/127.0.0.1:7003_solr (0)
[junit4:junit4]   1>   /live_nodes/127.0.0.1:7002_solr (0)
[junit4:junit4]   1>  /overseer (3)
[junit4:junit4]   1>  DATA:
[junit4:junit4]   1>      
[junit4:junit4]   1>   /overseer/queue (0)
[junit4:junit4]   1>   DATA:
[junit4:junit4]   1>      
[junit4:junit4]   1>   /overseer/queue-work (0)
[junit4:junit4]   1>   DATA:
[junit4:junit4]   1>      
[junit4:junit4]   1>   /overseer/collection-queue-work (0)
[junit4:junit4]   1>   DATA:
[junit4:junit4]   1>      
[junit4:junit4]   1>  /overseer_elect (2)
[junit4:junit4]   1>   /overseer_elect/election (5)
[junit4:junit4]   1>    /overseer_elect/election/88019624185692167-127.0.0.1:3333_solr-n_0000000005 (0)
[junit4:junit4]   1>    /overseer_elect/election/88019624185692166-127.0.0.1:7004_solr-n_0000000004 (0)
[junit4:junit4]   1>    /overseer_elect/election/88019624185692165-127.0.0.1:7003_solr-n_0000000003 (0)
[junit4:junit4]   1>    /overseer_elect/election/88019624185692164-127.0.0.1:7002_solr-n_0000000002 (0)
[junit4:junit4]   1>    /overseer_elect/election/88019624185692163-127.0.0.1:7001_solr-n_0000000001 (0)
[junit4:junit4]   1>   /overseer_elect/leader (0)
[junit4:junit4]   1>   DATA:
[junit4:junit4]   1>       {"id":"88019624185692163-127.0.0.1:7001_solr-n_0000000001"}
[junit4:junit4]   1>  /collections (2)
[junit4:junit4]   1>   /collections/collection1 (3)
[junit4:junit4]   1>   DATA:
[junit4:junit4]   1>       {"configName":"conf1"}
[junit4:junit4]   1>    /collections/collection1/shards (0)
[junit4:junit4]   1>    /collections/collection1/leader_elect (2)
[junit4:junit4]   1>     /collections/collection1/leader_elect/shard1 (1)
[junit4:junit4]   1>      /collections/collection1/leader_elect/shard1/election (5)
[junit4:junit4]   1>       /collections/collection1/leader_elect/shard1/election/88019624185692166-127.0.0.1:7004_solr_collection1-n_0000000005 (0)
[junit4:junit4]   1>       /collections/collection1/leader_elect/shard1/election/88019624185692164-127.0.0.1:7002_solr_collection1-n_0000000003 (0)
[junit4:junit4]   1>       /collections/collection1/leader_elect/shard1/election/88019624185692163-127.0.0.1:7001_solr_collection1-n_0000000008 (0)
[junit4:junit4]   1>       /collections/collection1/leader_elect/shard1/election/88019624185692164-127.0.0.1:7002_solr_collection1-n_0000000002 (0)
[junit4:junit4]   1>       /collections/collection1/leader_elect/shard1/election/88019624185692165-127.0.0.1:7003_solr_collection1-n_0000000004 (0)
[junit4:junit4]   1>     /collections/collection1/leader_elect/shard2 (1)
[junit4:junit4]   1>      /collections/collection1/leader_elect/shard2/election (1)
[junit4:junit4]   1>       /collections/collection1/leader_elect/shard2/election/88019624185692167-127.0.0.1:3333_solr_collection1-n_0000000000 (0)
[junit4:junit4]   1>    /collections/collection1/leaders (1)
[junit4:junit4]   1>     /collections/collection1/leaders/shard2 (0)
[junit4:junit4]   1>     DATA:
[junit4:junit4]   1>         {
[junit4:junit4]   1>           "core":"collection1",
[junit4:junit4]   1>           "node_name":"127.0.0.1:3333_solr",
[junit4:junit4]   1>           "base_url":"http://127.0.0.1:3333/solr"}
[junit4:junit4]   1>   /collections/control_collection (1)
[junit4:junit4]   1>   DATA:
[junit4:junit4]   1>       {"configName":"conf1"}
[junit4:junit4]   1>    /collections/control_collection/shards (0)
[junit4:junit4]   1>  /clusterstate.json (0)
[junit4:junit4]   1>  DATA:
[junit4:junit4]   1>      {"collection1":{
[junit4:junit4]   2> 122388 T2356 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138b561c0da0008
[junit4:junit4]   2> 122390 T2319 oaz.ZooKeeper.close Session: 0x138b561c0da0008 closed
[junit4:junit4]   2> 122390 T2353 oazs.NIOServerCnxn.doIO WARNING EndOfStreamException: Unable to read additional data from client sessionid 0x138b561c0da0008, likely client has closed socket
[junit4:junit4]   2> 122391 T2319 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=41581215
[junit4:junit4]   2> 122391 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58650 which had sessionid 0x138b561c0da0008
[junit4:junit4]   2> 122391 T2319 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4de03050
[junit4:junit4]   2> 122391 T2382 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 122399 T2319 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 122399 T2319 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 122401 T2356 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138b561c0da0004
[junit4:junit4]   2> 122404 T2376 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 122405 T2379 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 122405 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58638 which had sessionid 0x138b561c0da0004
[junit4:junit4]   2> 122406 T2367 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 122406 T2319 oaz.ZooKeeper.close Session: 0x138b561c0da0004 closed
[junit4:junit4]   2> 122406 T2319 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1756149052
[junit4:junit4]   2> 122406 T2319 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4c7f3c76
[junit4:junit4]   2> 122410 T2319 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 122410 T2319 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 122412 T2356 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138b561c0da0005
[junit4:junit4]   2> 122414 T2376 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 122415 T2367 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 122415 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58641 which had sessionid 0x138b561c0da0005
[junit4:junit4]   2> 122415 T2379 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 122416 T2319 oaz.ZooKeeper.close Session: 0x138b561c0da0005 closed
[junit4:junit4]   2> 122416 T2319 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1535086333
[junit4:junit4]   2> 122417 T2319 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@302979d2
[junit4:junit4]   2> 122419 T2373 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard1/election/88019624185692165-127.0.0.1:7003_solr_collection1-n_0000000004
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> at org.apache.zookeeper.ZooKeeper.delete(ZooKeeper.java:734)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:166)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$2.execute(SolrZkClient.java:163)
[junit4:junit4]   2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient.delete(SolrZkClient.java:163)
[junit4:junit4]   2> at org.apache.solr.cloud.ElectionContext.cancelElection(ElectionContext.java:59)
[junit4:junit4]   2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:142)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:151)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:96)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:57)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:125)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2>
[junit4:junit4]   2> 122420 T2373 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 122420 T2373 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 122420 T2373 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1249)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:266)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:263)
[junit4:junit4]   2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:263)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:91)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:57)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:125)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2>
[junit4:junit4]   2> 122421 T2373 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 122421 T2319 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 122421 T2319 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 122422 T2376 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:7004/solr/collection1/
[junit4:junit4]   2> 122423 T2376 oasc.SyncStrategy.sync SEVERE No UpdateLog found - cannot sync
[junit4:junit4]   2> 122423 T2376 oasc.ShardLeaderElectionContext.rejoinLeaderElection There is a better leader candidate than us - going back into recovery
[junit4:junit4]   2> 122426 T2356 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138b561c0da0003
[junit4:junit4]   2> 122431 T2367 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 122431 T2379 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 122432 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58637 which had sessionid 0x138b561c0da0003
[junit4:junit4]   2> 122432 T2319 oaz.ZooKeeper.close Session: 0x138b561c0da0003 closed
[junit4:junit4]   2> 122433 T2383 oasc.Overseer$CloudStateUpdater.amILeader WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
[junit4:junit4]   2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
[junit4:junit4]   2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:187)
[junit4:junit4]   2> at org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:113)
[junit4:junit4]   2> at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   2>
[junit4:junit4]   2> 122433 T2319 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1116973420
[junit4:junit4]   2> 122433 T2383 oasc.Overseer$CloudStateUpdater.amILeader According to ZK I (id=88019624185692163-127.0.0.1:7001_solr-n_0000000001) am no longer a leader.
[junit4:junit4]   2> 122435 T2376 oasu.DefaultSolrCoreState.doRecovery WARNING Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4:junit4]   2> 122435 T2356 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138b561c0da0006
[junit4:junit4]   2> 122437 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58644 which had sessionid 0x138b561c0da0006
[junit4:junit4]   2> 122437 T2319 oaz.ZooKeeper.close Session: 0x138b561c0da0006 closed
[junit4:junit4]   2> 122439 T2379 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 122439 T2319 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=748160780
[junit4:junit4]   2> 122439 T2319 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@2a9f6888
[junit4:junit4]   2> 122442 T2319 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 122442 T2319 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 122443 T2356 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x138b561c0da0007
[junit4:junit4]   2> 122447 T2353 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:58647 which had sessionid 0x138b561c0da0007
[junit4:junit4]   2> 122447 T2319 oaz.ZooKeeper.close Session: 0x138b561c0da0007 closed
[junit4:junit4]   2> 122448 T2356 oazs.PrepRequestProcessor.run PrepRequestProcessor exited loop!
[junit4:junit4]   2> 122448 T2355 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4]   2> 122448 T2319 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 123388 T2370 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1249)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:266)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:263)
[junit4:junit4]   2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:263)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:91)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:57)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:125)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2>
[junit4:junit4]   2> 123388 T2370 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 123388 T2370 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 123388 T2370 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 123415 T2367 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 123415 T2367 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 123421 T2376 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@431a38e0
[junit4:junit4]   2> 123422 T2376 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 123422 T2376 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 123423 T2376 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1249)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:266)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:263)
[junit4:junit4]   2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:263)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:228)
[junit4:junit4]   2> at org.apache.solr.cloud.ShardLeaderElectionContext.rejoinLeaderElection(ElectionContext.java:194)
[junit4:junit4]   2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:160)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:151)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:96)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:57)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:125)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2>
[junit4:junit4]   2> 123423 T2376 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/election
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1249)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:266)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:263)
[junit4:junit4]   2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:263)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:91)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:57)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:125)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2>
[junit4:junit4]   2> 123424 T2376 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 123424 T2376 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 123424 T2376 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 123429 T2379 oasc.LeaderElector$1.process WARNING  org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4]   2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4]   2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:815)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:199)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient$3.execute(SolrZkClient.java:196)
[junit4:junit4]   2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:196)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:510)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:431)
[junit4:junit4]   2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:412)
[junit4:junit4]   2> at org.apache.solr.cloud.OverseerElectionContext.runLeaderProcess(ElectionContext.java:272)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:151)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:96)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:57)
[junit4:junit4]   2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:125)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:526)
[junit4:junit4]   2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:502)
[junit4:junit4]   2>
[junit4:junit4]   2> 123429 T2379 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 123429 T2379 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 123429 T2379 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 124664 T2353 oazs.NIOServerCnxn$Factory.run WARNING Ignoring unexpected runtime exception java.nio.channels.CancelledKeyException
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:55)
[junit4:junit4]   2> at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:69)
[junit4:junit4]   2> at org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:241)
[junit4:junit4]   2>
[junit4:junit4]   2> 124665 T2353 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited run method
[junit4:junit4]   2> 124665 T2319 oazs.FinalRequestProcessor.shutdown shutdown of request processor complete
[junit4:junit4]   2> 124667 T2319 oas.SolrTestCaseJ4.tearDown ###Ending testSimpleSliceLeaderElection
[junit4:junit4]   1>          "shard1":{
[junit4:junit4]   1>            "127.0.0.1:7000_solr_collection1":{
[junit4:junit4]   1>              "shard":"shard1",
[junit4:junit4]   1>              "roles":null,
[junit4:junit4]   1>              "leader":"true",
[junit4:junit4]   1>              "state":"active",
[junit4:junit4]   1>              "core":"collection1",
[junit4:junit4]   1>              "collection":"collection1",
[junit4:junit4]   1>              "node_name":"127.0.0.1:7000_solr",
[junit4:junit4]   1>              "base_url":"http://127.0.0.1:7000/solr"},
[junit4:junit4]   1>            "127.0.0.1:7001_solr_collection1":{
[junit4:junit4]   1>              "shard":"shard1",
[junit4:junit4]   1>              "roles":null,
[junit4:junit4]   1>              "state":"down",
[junit4:junit4]   1>              "core":"collection1",
[junit4:junit4]   1>              "collection":"collection1",
[junit4:junit4]   1>              "node_name":"127.0.0.1:7001_solr",
[junit4:junit4]   1>              "base_url":"http://127.0.0.1:7001/solr"},
[junit4:junit4]   1>            "127.0.0.1:7002_solr_collection1":{
[junit4:junit4]   1>              "shard":"shard1",
[junit4:junit4]   1>              "roles":null,
[junit4:junit4]   1>              "state":"active",
[junit4:junit4]   1>              "core":"collection1",
[junit4:junit4]   1>              "collection":"collection1",
[junit4:junit4]   1>              "node_name":"127.0.0.1:7002_solr",
[junit4:junit4]   1>              "base_url":"http://127.0.0.1:7002/solr"},
[junit4:junit4]   1>            "127.0.0.1:7003_solr_collection1":{
[junit4:junit4]   1>              "shard":"shard1",
[junit4:junit4]   1>              "roles":null,
[junit4:junit4]   1>              "state":"active",
[junit4:junit4]   1>              "core":"collection1",
[junit4:junit4]   1>              "collection":"collection1",
[junit4:junit4]   1>              "node_name":"127.0.0.1:7003_solr",
[junit4:junit4]   1>              "base_url":"http://127.0.0.1:7003/solr"},
[junit4:junit4]   1>            "127.0.0.1:7004_solr_collection1":{
[junit4:junit4]   1>              "shard":"shard1",
[junit4:junit4]   1>              "roles":null,
[junit4:junit4]   1>              "state":"active",
[junit4:junit4]   1>              "core":"collection1",
[junit4:junit4]   1>              "collection":"collection1",
[junit4:junit4]   1>              "node_name":"127.0.0.1:7004_solr",
[junit4:junit4]   1>              "base_url":"http://127.0.0.1:7004/solr"}},
[junit4:junit4]   1>          "shard2":{"127.0.0.1:3333_solr_collection1":{
[junit4:junit4]   1>              "shard":"shard2",
[junit4:junit4]   1>              "roles":null,
[junit4:junit4]   1>              "leader":"true",
[junit4:junit4]   1>              "state":"active",
[junit4:junit4]   1>              "core":"collection1",
[junit4:junit4]   1>              "collection":"collection1",
[junit4:junit4]   1>              "node_name":"127.0.0.1:3333_solr",
[junit4:junit4]   1>              "base_url":"http://127.0.0.1:3333/solr"}}}}
[junit4:junit4]   1>
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=LeaderElectionIntegrationTest -Dtests.method=testSimpleSliceLeaderElection -Dtests.seed=FD65295BE419C0F3 -Dtests.slow=true -Dtests.locale=ar_LB -Dtests.timezone=Etc/GMT-8 -Dtests.file.encoding=UTF-8
[junit4:junit4]   1>
[junit4:junit4]   2>
[junit4:junit4]    > (@AfterClass output)
[junit4:junit4]   2> 125007 T2354 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> 126560 T2319 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {}, sim=DefaultSimilarity, locale=ar_LB, timezone=Etc/GMT-8
[junit4:junit4]   2> NOTE: Windows 7 6.1 amd64/Sun Microsystems Inc. 1.6.0_33 (64-bit)/cpus=2,threads=4,free=128403584,total=194445312
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestHTMLStripCharFilterFactory, DebugComponentTest, TestFoldingMultitermQuery, SystemInfoHandlerTest, TestSolrJ, CloudStateTest, RequestHandlersTest, TestSurroundQueryParser, JSONWriterTest, TestCSVLoader, SyncSliceTest, TestPorterStemFilterFactory, RecoveryZkTest, TestJapaneseTokenizerFactory, StandardRequestHandlerTest, DistributedQueryElevationComponentTest, FieldMutatingUpdateProcessorTest, CoreAdminHandlerTest, TestLuceneMatchVersion, TestPatternReplaceFilterFactory, TestPersianNormalizationFilterFactory, TestSearchPerf, SpellPossibilityIteratorTest, TestIBSimilarityFactory, TestConfig, TestGermanStemFilterFactory, BasicZkTest, DirectUpdateHandlerOptimizeTest, TestDistributedGrouping, TestCodecSupport, OpenExchangeRatesOrgProviderTest, TestMergePolicyConfig, FileUtilsTest, TestBeiderMorseFilterFactory, TestDistributedSearch, TestCJKWidthFilterFactory, TestStopFilterFactory, TestBinaryResponseWriter, RequiredFieldsTest, TestHungarianLightStemFilterFactory, ScriptEngineTest, LeaderElectionTest, DirectSolrSpellCheckerTest, LeaderElectionIntegrationTest]
[junit4:junit4]   2>
[junit4:junit4] Completed in 126.60s, 2 tests, 1 failure <<< FAILURES!

[...truncated 1035 lines...]
BUILD FAILED
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\build.xml:29: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\solr\build.xml:144: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\lucene\module-build.xml:62: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\lucene\common-build.xml:1094: The following error occurred while executing this line:
C:\Jenkins\workspace\Lucene-Solr-trunk-Windows-Java6-64\lucene\common-build.xml:789: There were test failures: 297 suites, 1054 tests, 1 failure, 10 ignored (5 assumptions)

Total time: 34 minutes 49 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure





---------------------------------------------------------------------
To unsubscribe, e-mail: [hidden email]
For additional commands, e-mail: [hidden email]