lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0-ea-b65) - Build # 4455 - Failure!
Date Sun, 24 Feb 2013 09:17:25 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/4455/
Java: 64bit/jdk1.8.0-ea-b65 -XX:+UseSerialGC

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

Error Message:
Still found shard

Stack Trace:
java.lang.AssertionError: Still found shard
	at __randomizedtesting.SeedInfo.seed([6ED03C4D2BB1A684:EF36B2555CEEC6B8]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.UnloadDistributedZkTest.testUnloadShardAndCollection(UnloadDistributedZkTest.java:120)
	at org.apache.solr.cloud.UnloadDistributedZkTest.doTest(UnloadDistributedZkTest.java:73)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:794)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:474)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 9509 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.UnloadDistributedZkTest
[junit4:junit4]   2> 1 T3048 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /sfi/z
[junit4:junit4]   2> 3 T3048 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366
[junit4:junit4]   2> 4 T3048 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 5 T3049 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 6 T3049 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 6 T3049 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 6 T3049 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 6 T3049 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 7 T3049 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 105 T3048 oasc.ZkTestServer.run start zk server on port:55865
[junit4:junit4]   2> 105 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@53ddc315
[junit4:junit4]   2> 106 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T3054 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 107 T3054 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 107 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36672
[junit4:junit4]   2> 107 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36672
[junit4:junit4]   2> 107 T3052 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 109 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb4990000 with negotiated timeout 10000 for client /127.0.0.1:36672
[junit4:junit4]   2> 109 T3054 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb4990000, negotiated timeout = 10000
[junit4:junit4]   2> 109 T3055 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@53ddc315 name:ZooKeeperConnection Watcher:127.0.0.1:55865 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 109 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 110 T3048 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 111 T3053 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0b7bb4990000
[junit4:junit4]   2> 112 T3055 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 112 T3050 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36672 which had sessionid 0x13d0b7bb4990000
[junit4:junit4]   2> 112 T3048 oaz.ZooKeeper.close Session: 0x13d0b7bb4990000 closed
[junit4:junit4]   2> 113 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@24a61625
[junit4:junit4]   2> 113 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 113 T3056 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 114 T3056 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 114 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36673
[junit4:junit4]   2> 114 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36673
[junit4:junit4]   2> 114 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb4990001 with negotiated timeout 10000 for client /127.0.0.1:36673
[junit4:junit4]   2> 114 T3056 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb4990001, negotiated timeout = 10000
[junit4:junit4]   2> 115 T3057 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@24a61625 name:ZooKeeperConnection Watcher:127.0.0.1:55865/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 115 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 115 T3048 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 117 T3048 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 118 T3048 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 119 T3048 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 120 T3048 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 120 T3048 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 122 T3048 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 123 T3048 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 125 T3048 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 125 T3048 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 126 T3048 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 126 T3048 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 128 T3048 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 128 T3048 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 129 T3048 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 130 T3048 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 131 T3048 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 131 T3048 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 133 T3048 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 133 T3048 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 134 T3048 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 135 T3048 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 136 T3053 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0b7bb4990001
[junit4:junit4]   2> 136 T3050 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36673 which had sessionid 0x13d0b7bb4990001
[junit4:junit4]   2> 136 T3048 oaz.ZooKeeper.close Session: 0x13d0b7bb4990001 closed
[junit4:junit4]   2> 137 T3057 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 178 T3048 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 190 T3 oasc.CoreContainer.finalize SEVERE CoreContainer was not shutdown prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!!  instance=160096117
[junit4:junit4]   2> 191 T3048 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57068
[junit4:junit4]   2> 191 T3048 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 191 T3048 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 192 T3048 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361697289499
[junit4:junit4]   2> 192 T3048 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361697289499/solr.xml
[junit4:junit4]   2> 192 T3048 oasc.CoreContainer.<init> New CoreContainer 1586471014
[junit4:junit4]   2> 192 T3048 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361697289499/'
[junit4:junit4]   2> 193 T3048 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361697289499/'
[junit4:junit4]   2> 205 T3048 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 206 T3048 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 206 T3048 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 206 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 206 T3048 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 207 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 207 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 207 T3048 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 207 T3048 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 208 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 213 T3048 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 220 T3048 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55865/solr
[junit4:junit4]   2> 221 T3048 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 221 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1e4883c8
[junit4:junit4]   2> 222 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 222 T3067 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 222 T3067 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 222 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36674
[junit4:junit4]   2> 222 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36674
[junit4:junit4]   2> 223 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb4990002 with negotiated timeout 20000 for client /127.0.0.1:36674
[junit4:junit4]   2> 223 T3067 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb4990002, negotiated timeout = 20000
[junit4:junit4]   2> 223 T3068 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e4883c8 name:ZooKeeperConnection Watcher:127.0.0.1:55865 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 223 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 224 T3053 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0b7bb4990002
[junit4:junit4]   2> 225 T3068 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 225 T3050 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36674 which had sessionid 0x13d0b7bb4990002
[junit4:junit4]   2> 225 T3048 oaz.ZooKeeper.close Session: 0x13d0b7bb4990002 closed
[junit4:junit4]   2> 225 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 227 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@699f06a9
[junit4:junit4]   2> 228 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 228 T3069 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 229 T3069 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 229 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36675
[junit4:junit4]   2> 229 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36675
[junit4:junit4]   2> 229 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb4990003 with negotiated timeout 20000 for client /127.0.0.1:36675
[junit4:junit4]   2> 229 T3069 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb4990003, negotiated timeout = 20000
[junit4:junit4]   2> 230 T3070 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@699f06a9 name:ZooKeeperConnection Watcher:127.0.0.1:55865/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 230 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 231 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 232 T3048 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 233 T3048 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57068_sfi%2Fz
[junit4:junit4]   2> 233 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:57068_sfi%2Fz Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:57068_sfi%2Fz
[junit4:junit4]   2> 234 T3048 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:57068_sfi%2Fz
[junit4:junit4]   2> 236 T3048 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 238 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:delete cxid:0x16 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 238 T3048 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 240 T3048 oasc.Overseer.start Overseer (id=89240193556086787-127.0.0.1:57068_sfi%2Fz-n_0000000000) starting
[junit4:junit4]   2> 240 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 241 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 241 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 241 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 242 T3072 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 242 T3048 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 246 T3048 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 248 T3071 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 250 T3073 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361697289499/collection1
[junit4:junit4]   2> 250 T3073 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 250 T3073 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 250 T3073 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 251 T3073 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361697289499/collection1/'
[junit4:junit4]   2> 251 T3073 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361697289499/collection1/lib/README' to classloader
[junit4:junit4]   2> 252 T3073 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361697289499/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 271 T3073 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 308 T3073 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 309 T3073 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 313 T3073 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 605 T3073 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 609 T3073 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 611 T3073 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 620 T3073 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 622 T3073 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 624 T3073 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 625 T3073 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 626 T3073 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361697289499/collection1/, dataDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/control/data/
[junit4:junit4]   2> 626 T3073 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5176f87c
[junit4:junit4]   2> 626 T3073 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 627 T3073 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/control/data forceNew:false
[junit4:junit4]   2> 627 T3073 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/control/data
[junit4:junit4]   2> 627 T3073 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/control/data/index/
[junit4:junit4]   2> 627 T3073 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 627 T3073 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/control/data/index forceNew:false
[junit4:junit4]   2> 628 T3073 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5dfc6d0c lockFactory=org.apache.lucene.store.NativeFSLockFactory@1fbc3b3b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 628 T3073 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 629 T3073 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/control/data/index
[junit4:junit4]   2> 630 T3073 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 630 T3073 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 630 T3073 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 630 T3073 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 631 T3073 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 631 T3073 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 631 T3073 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 631 T3073 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 631 T3073 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 633 T3073 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 635 T3073 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/control/data
[junit4:junit4]   2> 635 T3073 oass.SolrIndexSearcher.<init> Opening Searcher@1cde07f5 main
[junit4:junit4]   2> 635 T3073 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/control/data/tlog
[junit4:junit4]   2> 636 T3073 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 636 T3073 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 638 T3073 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/control/data
[junit4:junit4]   2> 638 T3074 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cde07f5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 638 T3073 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 638 T3073 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 639 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:create cxid:0x50 zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1750 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1751 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:57068/sfi/z",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:57068_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"control_collection"}
[junit4:junit4]   2> 1751 T3071 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 1751 T3071 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 1752 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:create cxid:0x58 zxid:0x2c txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1754 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 2642 T3073 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2642 T3073 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:57068/sfi/z collection:control_collection shard:shard1
[junit4:junit4]   2> 2643 T3073 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 2647 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:delete cxid:0x72 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 2647 T3073 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2647 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2648 T3073 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2648 T3073 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2648 T3073 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:57068/sfi/z/collection1/
[junit4:junit4]   2> 2649 T3073 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2649 T3073 oasc.SyncStrategy.syncToMe http://127.0.0.1:57068/sfi/z/collection1/ has no replicas
[junit4:junit4]   2> 2649 T3073 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:57068/sfi/z/collection1/
[junit4:junit4]   2> 2649 T3073 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 2651 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3257 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3263 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 3304 T3073 oasc.ZkController.register We are http://127.0.0.1:57068/sfi/z/collection1/ and leader is http://127.0.0.1:57068/sfi/z/collection1/
[junit4:junit4]   2> 3304 T3073 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:57068/sfi/z
[junit4:junit4]   2> 3304 T3073 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3304 T3073 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 3304 T3073 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3306 T3073 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3306 T3048 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3307 T3048 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3307 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3309 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 3310 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@53f97013
[junit4:junit4]   2> 3310 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3311 T3075 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3311 T3075 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 3311 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36678
[junit4:junit4]   2> 3311 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36678
[junit4:junit4]   2> 3312 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb4990004 with negotiated timeout 10000 for client /127.0.0.1:36678
[junit4:junit4]   2> 3312 T3075 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb4990004, negotiated timeout = 10000
[junit4:junit4]   2> 3312 T3076 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@53f97013 name:ZooKeeperConnection Watcher:127.0.0.1:55865/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3312 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3313 T3048 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3314 T3048 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 3358 T3048 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 3359 T3048 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:47457
[junit4:junit4]   2> 3360 T3048 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3360 T3048 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3360 T3048 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677
[junit4:junit4]   2> 3360 T3048 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/solr.xml
[junit4:junit4]   2> 3361 T3048 oasc.CoreContainer.<init> New CoreContainer 1310028428
[junit4:junit4]   2> 3361 T3048 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/'
[junit4:junit4]   2> 3361 T3048 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/'
[junit4:junit4]   2> 3374 T3048 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 3375 T3048 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3375 T3048 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 3375 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3376 T3048 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3376 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3376 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3376 T3048 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3377 T3048 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3377 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 3382 T3048 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3389 T3048 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55865/solr
[junit4:junit4]   2> 3390 T3048 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 3390 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@63ec0936
[junit4:junit4]   2> 3391 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3391 T3086 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3391 T3086 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 3391 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36679
[junit4:junit4]   2> 3391 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36679
[junit4:junit4]   2> 3392 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb4990005 with negotiated timeout 20000 for client /127.0.0.1:36679
[junit4:junit4]   2> 3392 T3086 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb4990005, negotiated timeout = 20000
[junit4:junit4]   2> 3392 T3087 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@63ec0936 name:ZooKeeperConnection Watcher:127.0.0.1:55865 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3392 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3394 T3053 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0b7bb4990005
[junit4:junit4]   2> 3394 T3087 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 3394 T3050 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36679 which had sessionid 0x13d0b7bb4990005
[junit4:junit4]   2> 3394 T3048 oaz.ZooKeeper.close Session: 0x13d0b7bb4990005 closed
[junit4:junit4]   2> 3395 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 3397 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@5b5cb5b2
[junit4:junit4]   2> 3398 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3398 T3088 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3398 T3088 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 3398 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36680
[junit4:junit4]   2> 3399 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36680
[junit4:junit4]   2> 3399 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb4990006 with negotiated timeout 20000 for client /127.0.0.1:36680
[junit4:junit4]   2> 3399 T3088 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb4990006, negotiated timeout = 20000
[junit4:junit4]   2> 3399 T3089 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5b5cb5b2 name:ZooKeeperConnection Watcher:127.0.0.1:55865/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3400 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3400 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3401 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3402 T3048 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4404 T3048 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47457_sfi%2Fz
[junit4:junit4]   2> 4404 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990006 type:delete cxid:0x9 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:47457_sfi%2Fz Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:47457_sfi%2Fz
[junit4:junit4]   2> 4405 T3048 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:47457_sfi%2Fz
[junit4:junit4]   2> 4407 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 4407 T3076 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4407 T3089 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4408 T3070 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4411 T3090 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/collection1
[junit4:junit4]   2> 4411 T3090 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4412 T3090 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4412 T3090 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4413 T3090 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/collection1/'
[junit4:junit4]   2> 4413 T3090 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/collection1/lib/README' to classloader
[junit4:junit4]   2> 4413 T3090 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4441 T3090 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4464 T3090 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4465 T3090 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4467 T3090 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4745 T3090 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4749 T3090 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4751 T3090 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4757 T3090 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4760 T3090 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4762 T3090 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4762 T3090 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4763 T3090 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/collection1/, dataDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1/
[junit4:junit4]   2> 4764 T3090 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5176f87c
[junit4:junit4]   2> 4764 T3090 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 4764 T3090 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1 forceNew:false
[junit4:junit4]   2> 4765 T3090 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1
[junit4:junit4]   2> 4765 T3090 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1/index/
[junit4:junit4]   2> 4765 T3090 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4765 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4766 T3090 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1/index forceNew:false
[junit4:junit4]   2> 4766 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:57068/sfi/z",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:57068_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"control_collection"}
[junit4:junit4]   2> 4767 T3090 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1163094f lockFactory=org.apache.lucene.store.NativeFSLockFactory@8ab241e),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4767 T3090 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 4767 T3090 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1/index
[junit4:junit4]   2> 4768 T3090 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4769 T3090 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4769 T3090 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4769 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 4769 T3090 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4769 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 4770 T3090 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4769 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 4770 T3090 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4770 T3090 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4770 T3090 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4771 T3090 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4772 T3090 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4774 T3090 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1
[junit4:junit4]   2> 4774 T3090 oass.SolrIndexSearcher.<init> Opening Searcher@196c4cbd main
[junit4:junit4]   2> 4775 T3090 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1/tlog
[junit4:junit4]   2> 4775 T3090 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4775 T3090 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4778 T3091 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@196c4cbd main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4779 T3090 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 4779 T3090 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6272 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6273 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47457/sfi/z",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47457_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 6273 T3071 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 6273 T3071 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 6276 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 6276 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 6276 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 6780 T3090 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 6781 T3090 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:47457/sfi/z collection:collection1 shard:shard2
[junit4:junit4]   2> 6781 T3090 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 6785 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990006 type:delete cxid:0x46 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 6786 T3090 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 6786 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990006 type:create cxid:0x47 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6787 T3090 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 6787 T3090 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 6788 T3090 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:47457/sfi/z/collection1/
[junit4:junit4]   2> 6788 T3090 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 6788 T3090 oasc.SyncStrategy.syncToMe http://127.0.0.1:47457/sfi/z/collection1/ has no replicas
[junit4:junit4]   2> 6788 T3090 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:47457/sfi/z/collection1/
[junit4:junit4]   2> 6788 T3090 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 6790 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990006 type:create cxid:0x51 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7779 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7784 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 7784 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 7784 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 7793 T3090 oasc.ZkController.register We are http://127.0.0.1:47457/sfi/z/collection1/ and leader is http://127.0.0.1:47457/sfi/z/collection1/
[junit4:junit4]   2> 7794 T3090 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:47457/sfi/z
[junit4:junit4]   2> 7794 T3090 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 7794 T3090 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 7794 T3090 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7795 T3090 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7796 T3048 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 7796 T3048 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7796 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7850 T3048 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 7852 T3048 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53632
[junit4:junit4]   2> 7852 T3048 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 7853 T3048 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7853 T3048 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170
[junit4:junit4]   2> 7853 T3048 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170/solr.xml
[junit4:junit4]   2> 7854 T3048 oasc.CoreContainer.<init> New CoreContainer 734537715
[junit4:junit4]   2> 7854 T3048 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170/'
[junit4:junit4]   2> 7854 T3048 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170/'
[junit4:junit4]   2> 7869 T3048 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 7870 T3048 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 7870 T3048 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 7870 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 7871 T3048 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 7871 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 7871 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 7871 T3048 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 7872 T3048 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 7872 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 7876 T3048 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 7884 T3048 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55865/solr
[junit4:junit4]   2> 7884 T3048 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 7885 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@df9a273
[junit4:junit4]   2> 7885 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7886 T3101 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 7886 T3101 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 7886 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36683
[junit4:junit4]   2> 7886 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36683
[junit4:junit4]   2> 7888 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb4990007 with negotiated timeout 20000 for client /127.0.0.1:36683
[junit4:junit4]   2> 7888 T3101 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb4990007, negotiated timeout = 20000
[junit4:junit4]   2> 7888 T3102 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@df9a273 name:ZooKeeperConnection Watcher:127.0.0.1:55865 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 7888 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 7889 T3053 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0b7bb4990007
[junit4:junit4]   2> 7890 T3102 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 7890 T3050 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36683 which had sessionid 0x13d0b7bb4990007
[junit4:junit4]   2> 7890 T3048 oaz.ZooKeeper.close Session: 0x13d0b7bb4990007 closed
[junit4:junit4]   2> 7890 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 7893 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@473253e7
[junit4:junit4]   2> 7894 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7894 T3103 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 7895 T3103 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 7895 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36684
[junit4:junit4]   2> 7895 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36684
[junit4:junit4]   2> 7895 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb4990008 with negotiated timeout 20000 for client /127.0.0.1:36684
[junit4:junit4]   2> 7896 T3103 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb4990008, negotiated timeout = 20000
[junit4:junit4]   2> 7896 T3104 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@473253e7 name:ZooKeeperConnection Watcher:127.0.0.1:55865/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 7896 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 7897 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7898 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7898 T3048 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8900 T3048 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53632_sfi%2Fz
[junit4:junit4]   2> 8901 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990008 type:delete cxid:0x9 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:53632_sfi%2Fz Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:53632_sfi%2Fz
[junit4:junit4]   2> 8902 T3048 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53632_sfi%2Fz
[junit4:junit4]   2> 8903 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   2> 8904 T3104 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 8904 T3089 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 8904 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 8904 T3070 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 8905 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 8905 T3076 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 8908 T3105 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170/collection1
[junit4:junit4]   2> 8909 T3105 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8909 T3105 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8909 T3105 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8910 T3105 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170/collection1/'
[junit4:junit4]   2> 8910 T3105 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170/collection1/lib/README' to classloader
[junit4:junit4]   2> 8910 T3105 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8928 T3105 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 8962 T3105 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 8963 T3105 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8966 T3105 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9250 T3105 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9254 T3105 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9256 T3105 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9263 T3105 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9265 T3105 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9267 T3105 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9268 T3105 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9269 T3105 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170/collection1/, dataDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2/
[junit4:junit4]   2> 9269 T3105 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5176f87c
[junit4:junit4]   2> 9269 T3105 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 9270 T3105 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2 forceNew:false
[junit4:junit4]   2> 9270 T3105 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2
[junit4:junit4]   2> 9270 T3105 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2/index/
[junit4:junit4]   2> 9270 T3105 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 9270 T3105 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2/index forceNew:false
[junit4:junit4]   2> 9271 T3105 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@562f3bd3 lockFactory=org.apache.lucene.store.NativeFSLockFactory@13bd45c5),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9271 T3105 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 9272 T3105 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2/index
[junit4:junit4]   2> 9273 T3105 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 9273 T3105 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 9273 T3105 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 9273 T3105 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9274 T3105 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9274 T3105 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 9274 T3105 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 9274 T3105 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9275 T3105 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9276 T3105 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9278 T3105 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2
[junit4:junit4]   2> 9278 T3105 oass.SolrIndexSearcher.<init> Opening Searcher@c5b8dbf main
[junit4:junit4]   2> 9279 T3105 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2/tlog
[junit4:junit4]   2> 9279 T3105 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9279 T3105 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9281 T3105 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2
[junit4:junit4]   2> 9281 T3106 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c5b8dbf main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9281 T3105 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 9282 T3105 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9288 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9289 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47457/sfi/z",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47457_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 9291 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:53632/sfi/z",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:53632_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 9291 T3071 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 9291 T3071 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 9294 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9294 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9294 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 9294 T3104 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 10282 T3105 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 10283 T3105 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53632/sfi/z collection:collection1 shard:shard1
[junit4:junit4]   2> 10284 T3105 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 10288 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990008 type:delete cxid:0x43 zxid:0x7d txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> 10288 T3105 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 10289 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990008 type:create cxid:0x44 zxid:0x7e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 10290 T3105 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 10290 T3105 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 10290 T3105 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53632/sfi/z/collection1/
[junit4:junit4]   2> 10290 T3105 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 10290 T3105 oasc.SyncStrategy.syncToMe http://127.0.0.1:53632/sfi/z/collection1/ has no replicas
[junit4:junit4]   2> 10290 T3105 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53632/sfi/z/collection1/
[junit4:junit4]   2> 10290 T3105 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 10292 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990008 type:create cxid:0x4d zxid:0x81 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 10798 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10804 T3104 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 10804 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 10804 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 10804 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 10846 T3105 oasc.ZkController.register We are http://127.0.0.1:53632/sfi/z/collection1/ and leader is http://127.0.0.1:53632/sfi/z/collection1/
[junit4:junit4]   2> 10846 T3105 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53632/sfi/z
[junit4:junit4]   2> 10846 T3105 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 10846 T3105 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 10846 T3105 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10848 T3105 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10848 T3048 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 10848 T3048 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 10849 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10899 T3048 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 10901 T3048 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:38111
[junit4:junit4]   2> 10902 T3048 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 10902 T3048 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 10902 T3048 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361697300214
[junit4:junit4]   2> 10902 T3048 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361697300214/solr.xml
[junit4:junit4]   2> 10903 T3048 oasc.CoreContainer.<init> New CoreContainer 508172016
[junit4:junit4]   2> 10903 T3048 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361697300214/'
[junit4:junit4]   2> 10903 T3048 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361697300214/'
[junit4:junit4]   2> 10916 T3048 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 10916 T3048 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 10916 T3048 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 10917 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 10917 T3048 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 10917 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 10917 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 10918 T3048 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 10918 T3048 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 10918 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 10923 T3048 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 10930 T3048 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55865/solr
[junit4:junit4]   2> 10931 T3048 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 10931 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@673a2732
[junit4:junit4]   2> 10932 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10932 T3116 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 10932 T3116 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 10932 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36688
[junit4:junit4]   2> 10933 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36688
[junit4:junit4]   2> 10933 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb4990009 with negotiated timeout 20000 for client /127.0.0.1:36688
[junit4:junit4]   2> 10933 T3116 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb4990009, negotiated timeout = 20000
[junit4:junit4]   2> 10934 T3117 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@673a2732 name:ZooKeeperConnection Watcher:127.0.0.1:55865 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10934 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10935 T3053 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0b7bb4990009
[junit4:junit4]   2> 10935 T3117 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 10935 T3050 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36688 which had sessionid 0x13d0b7bb4990009
[junit4:junit4]   2> 10935 T3048 oaz.ZooKeeper.close Session: 0x13d0b7bb4990009 closed
[junit4:junit4]   2> 10936 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 10938 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@72a19902
[junit4:junit4]   2> 10939 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10939 T3118 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 10939 T3118 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 10939 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36689
[junit4:junit4]   2> 10940 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36689
[junit4:junit4]   2> 10940 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb499000a with negotiated timeout 20000 for client /127.0.0.1:36689
[junit4:junit4]   2> 10940 T3118 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb499000a, negotiated timeout = 20000
[junit4:junit4]   2> 10940 T3119 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@72a19902 name:ZooKeeperConnection Watcher:127.0.0.1:55865/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10941 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10942 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb499000a type:create cxid:0x1 zxid:0x8e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 10942 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb499000a type:create cxid:0x2 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 10944 T3048 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 11945 T3048 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38111_sfi%2Fz
[junit4:junit4]   2> 11946 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb499000a type:delete cxid:0x9 zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:38111_sfi%2Fz Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:38111_sfi%2Fz
[junit4:junit4]   2> 11947 T3048 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:38111_sfi%2Fz
[junit4:junit4]   2> 11948 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   2> 11949 T3076 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 11949 T3089 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 11949 T3104 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 11949 T3104 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 11949 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 11949 T3119 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 11949 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 11950 T3070 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 11954 T3120 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361697300214/collection1
[junit4:junit4]   2> 11954 T3120 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 11955 T3120 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 11955 T3120 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 11956 T3120 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361697300214/collection1/'
[junit4:junit4]   2> 11956 T3120 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361697300214/collection1/lib/README' to classloader
[junit4:junit4]   2> 11956 T3120 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361697300214/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 11985 T3120 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 12008 T3120 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 12009 T3120 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 12012 T3120 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 12308 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12308 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:53632/sfi/z",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:53632_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 12311 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 12311 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 12311 T3104 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 12311 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 12312 T3119 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 12350 T3120 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 12354 T3120 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 12356 T3120 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 12365 T3120 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 12368 T3120 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 12370 T3120 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 12371 T3120 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 12372 T3120 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361697300214/collection1/, dataDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/
[junit4:junit4]   2> 12372 T3120 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5176f87c
[junit4:junit4]   2> 12373 T3120 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 12373 T3120 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3 forceNew:false
[junit4:junit4]   2> 12374 T3120 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3
[junit4:junit4]   2> 12374 T3120 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index/
[junit4:junit4]   2> 12374 T3120 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 12375 T3120 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index forceNew:false
[junit4:junit4]   2> 12376 T3120 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2a4e0cff lockFactory=org.apache.lucene.store.NativeFSLockFactory@552691a7),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12376 T3120 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 12377 T3120 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index
[junit4:junit4]   2> 12378 T3120 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 12378 T3120 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 12379 T3120 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 12379 T3120 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 12380 T3120 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 12380 T3120 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 12380 T3120 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 12381 T3120 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 12381 T3120 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 12384 T3120 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 12387 T3120 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3
[junit4:junit4]   2> 12388 T3120 oass.SolrIndexSearcher.<init> Opening Searcher@5396e24e main
[junit4:junit4]   2> 12388 T3120 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/tlog
[junit4:junit4]   2> 12389 T3120 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 12389 T3120 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 12392 T3120 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3
[junit4:junit4]   2> 12393 T3120 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 12393 T3121 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5396e24e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 12393 T3120 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 13815 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13816 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:38111/sfi/z",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:38111_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 13816 T3071 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 13816 T3071 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 13819 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 13819 T3104 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 13819 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 13819 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 13819 T3119 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 14394 T3120 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 14395 T3120 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:38111/sfi/z collection:collection1 shard:shard2
[junit4:junit4]   2> 14397 T3120 oasc.ZkController.register We are http://127.0.0.1:38111/sfi/z/collection1/ and leader is http://127.0.0.1:47457/sfi/z/collection1/
[junit4:junit4]   2> 14397 T3120 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:38111/sfi/z
[junit4:junit4]   2> 14397 T3120 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 14397 T3120 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C247 name=collection1 org.apache.solr.core.SolrCore@20d5aedd url=http://127.0.0.1:38111/sfi/z/collection1 node=127.0.0.1:38111_sfi%2Fz C247_STATE=coll:collection1 core:collection1 props:{shard=null, roles=null, core=collection1, base_url=http://127.0.0.1:38111/sfi/z, state=down, node_name=127.0.0.1:38111_sfi%2Fz, collection=collection1}
[junit4:junit4]   2> 14397 T3122 C247 P38111 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 14398 T3122 C247 P38111 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 14398 T3120 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14398 T3122 C247 P38111 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 14398 T3122 C247 P38111 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 14399 T3048 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 14399 T3048 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 14399 T3122 C247 P38111 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 14399 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 14454 T3048 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 14456 T3048 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56505
[junit4:junit4]   2> 14456 T3048 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 14457 T3048 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 14457 T3048 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361697303763
[junit4:junit4]   2> 14457 T3048 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361697303763/solr.xml
[junit4:junit4]   2> 14458 T3048 oasc.CoreContainer.<init> New CoreContainer 41838186
[junit4:junit4]   2> 14458 T3048 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361697303763/'
[junit4:junit4]   2> 14458 T3048 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361697303763/'
[junit4:junit4]   2> 14471 T3048 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 14471 T3048 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 14472 T3048 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 14472 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 14472 T3048 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 14472 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 14473 T3048 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 14473 T3048 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 14473 T3048 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 14473 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 14478 T3048 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 14486 T3048 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55865/solr
[junit4:junit4]   2> 14486 T3048 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 14486 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@12c2a128
[junit4:junit4]   2> 14487 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 14487 T3132 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 14488 T3132 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 14488 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36700
[junit4:junit4]   2> 14488 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36700
[junit4:junit4]   2> 14489 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb499000b with negotiated timeout 20000 for client /127.0.0.1:36700
[junit4:junit4]   2> 14489 T3132 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb499000b, negotiated timeout = 20000
[junit4:junit4]   2> 14489 T3133 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12c2a128 name:ZooKeeperConnection Watcher:127.0.0.1:55865 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 14489 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 14490 T3053 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0b7bb499000b
[junit4:junit4]   2> 14491 T3133 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 14491 T3050 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:36700 which had sessionid 0x13d0b7bb499000b
[junit4:junit4]   2> 14491 T3048 oaz.ZooKeeper.close Session: 0x13d0b7bb499000b closed
[junit4:junit4]   2> 14491 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 14493 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1524909d
[junit4:junit4]   2> 14494 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 14494 T3134 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 14495 T3134 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 14495 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36701
[junit4:junit4]   2> 14495 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36701
[junit4:junit4]   2> 14496 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb499000c with negotiated timeout 20000 for client /127.0.0.1:36701
[junit4:junit4]   2> 14496 T3134 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb499000c, negotiated timeout = 20000
[junit4:junit4]   2> 14496 T3135 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1524909d name:ZooKeeperConnection Watcher:127.0.0.1:55865/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 14496 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 14497 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb499000c type:create cxid:0x1 zxid:0xa1 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 14497 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb499000c type:create cxid:0x2 zxid:0xa2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 14498 T3048 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 15323 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15323 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:38111/sfi/z",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:38111_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 15327 T3135 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15327 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15327 T3119 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15327 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15327 T3104 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15327 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15500 T3048 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56505_sfi%2Fz
[junit4:junit4]   2> 15500 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb499000c type:delete cxid:0xb zxid:0xa7 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:56505_sfi%2Fz Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:56505_sfi%2Fz
[junit4:junit4]   2> 15502 T3048 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56505_sfi%2Fz
[junit4:junit4]   2> 15504 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15504 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15504 T3135 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   2> 15505 T3119 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 15505 T3076 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 15505 T3119 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 15505 T3104 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 15505 T3104 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 15505 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 15506 T3070 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 15506 T3089 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 15506 T3135 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 15509 T3136 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361697303763/collection1
[junit4:junit4]   2> 15510 T3136 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 15510 T3136 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 15510 T3136 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 15511 T3136 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361697303763/collection1/'
[junit4:junit4]   2> 15512 T3136 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361697303763/collection1/lib/README' to classloader
[junit4:junit4]   2> 15512 T3136 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361697303763/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 15530 T3136 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 15567 T3136 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 15568 T3136 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 15570 T3136 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 15865 T3136 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 15869 T3136 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 15871 T3136 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 15880 T3136 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 15882 T3136 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 15884 T3136 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 15885 T3136 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 15886 T3136 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361697303763/collection1/, dataDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/
[junit4:junit4]   2> 15886 T3136 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5176f87c
[junit4:junit4]   2> 15886 T3136 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 15887 T3136 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4 forceNew:false
[junit4:junit4]   2> 15887 T3136 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4
[junit4:junit4]   2> 15887 T3136 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index/
[junit4:junit4]   2> 15887 T3136 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 15888 T3136 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index forceNew:false
[junit4:junit4]   2> 15888 T3136 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@737e55af lockFactory=org.apache.lucene.store.NativeFSLockFactory@78d366da),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 15889 T3136 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 15889 T3136 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index
[junit4:junit4]   2> 15890 T3136 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 15890 T3136 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 15890 T3136 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 15891 T3136 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 15891 T3136 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 15891 T3136 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 15891 T3136 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 15891 T3136 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 15892 T3136 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 15894 T3136 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 15895 T3136 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4
[junit4:junit4]   2> 15896 T3136 oass.SolrIndexSearcher.<init> Opening Searcher@558c5ef1 main
[junit4:junit4]   2> 15896 T3136 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/tlog
[junit4:junit4]   2> 15896 T3136 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 15896 T3136 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 15898 T3136 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4
[junit4:junit4]   2> 15899 T3137 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@558c5ef1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 15899 T3136 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 15899 T3136 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16831 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16831 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56505/sfi/z",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:56505_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 16831 T3071 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 16831 T3071 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 16833 T3119 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 16833 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 16834 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 16834 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 16833 T3135 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 16833 T3104 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 16900 T3136 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 16900 T3136 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:56505/sfi/z collection:collection1 shard:shard1
[junit4:junit4]   2> 16902 T3136 oasc.ZkController.register We are http://127.0.0.1:56505/sfi/z/collection1/ and leader is http://127.0.0.1:53632/sfi/z/collection1/
[junit4:junit4]   2> 16902 T3136 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56505/sfi/z
[junit4:junit4]   2> 16903 T3136 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 16903 T3136 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C248 name=collection1 org.apache.solr.core.SolrCore@2a4e6f04 url=http://127.0.0.1:56505/sfi/z/collection1 node=127.0.0.1:56505_sfi%2Fz C248_STATE=coll:collection1 core:collection1 props:{shard=null, roles=null, core=collection1, base_url=http://127.0.0.1:56505/sfi/z, state=down, node_name=127.0.0.1:56505_sfi%2Fz, collection=collection1}
[junit4:junit4]   2> 16903 T3138 C248 P56505 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 16903 T3136 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16904 T3138 C248 P56505 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 16904 T3138 C248 P56505 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 16904 T3138 C248 P56505 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16904 T3048 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 16905 T3048 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 16905 T3138 C248 P56505 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16905 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16908 T3048 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16908 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16911 T3084 oasc.CoreContainer.create Creating SolrCore 'unloadcollection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/unloadcollection1
[junit4:junit4]   2> 16911 T3084 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4]   2> 16912 T3084 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:unloadcollection
[junit4:junit4]   2> 16912 T3084 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4]   2> 16913 T3084 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4]   2> 16913 T3084 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection
[junit4:junit4]   2> 16914 T3084 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4]   2> 16915 T3084 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/unloadcollection1/'
[junit4:junit4]   2> 16938 T3084 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 16976 T3084 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 16977 T3084 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 16980 T3084 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 17289 T3084 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 17293 T3084 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 17295 T3084 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 17303 T3084 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17305 T3084 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17308 T3084 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17309 T3084 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17309 T3084 oasc.SolrCore.<init> [unloadcollection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/unloadcollection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697306272unloadcollection1_1n/
[junit4:junit4]   2> 17310 T3084 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5176f87c
[junit4:junit4]   2> 17310 T3084 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 17311 T3084 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697306272unloadcollection1_1n forceNew:false
[junit4:junit4]   2> 17311 T3084 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697306272unloadcollection1_1n
[junit4:junit4]   2> 17311 T3084 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697306272unloadcollection1_1n/index/
[junit4:junit4]   2> 17311 T3084 oasc.SolrCore.initIndex WARNING [unloadcollection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697306272unloadcollection1_1n/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 17312 T3084 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697306272unloadcollection1_1n/index forceNew:false
[junit4:junit4]   2> 17313 T3084 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@270ba82a lockFactory=org.apache.lucene.store.NativeFSLockFactory@1e51a207),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 17313 T3084 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 17313 T3084 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697306272unloadcollection1_1n/index
[junit4:junit4]   2> 17314 T3084 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 17315 T3084 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 17315 T3084 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 17315 T3084 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 17316 T3084 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 17316 T3084 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 17316 T3084 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 17316 T3084 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 17317 T3084 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 17319 T3084 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 17321 T3084 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697306272unloadcollection1_1n
[junit4:junit4]   2> 17321 T3084 oass.SolrIndexSearcher.<init> Opening Searcher@241a54b5 main
[junit4:junit4]   2> 17321 T3084 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697306272unloadcollection1_1n/tlog
[junit4:junit4]   2> 17322 T3084 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 17322 T3084 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 17324 T3084 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697306272unloadcollection1_1n
[junit4:junit4]   2> 17324 T3139 oasc.SolrCore.registerSearcher [unloadcollection1] Registered new searcher Searcher@241a54b5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 17325 T3084 oasc.ZkController.publish publishing core=unloadcollection1 state=down
[junit4:junit4]   2>  C247_STATE=coll:collection1 core:collection1 props:{shard=shard2, roles=null, core=collection1, base_url=http://127.0.0.1:38111/sfi/z, state=recovering, node_name=127.0.0.1:38111_sfi%2Fz, collection=collection1}
[junit4:junit4]   2> 17403 T3122 C247 P38111 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:47457/sfi/z/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 17403 T3122 C247 P38111 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:38111/sfi/z START replicas=[http://127.0.0.1:47457/sfi/z/collection1/] nUpdates=100
[junit4:junit4]   2> 17404 T3122 C247 P38111 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 17404 T3122 C247 P38111 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 17404 T3122 C247 P38111 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 17404 T3122 C247 P38111 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 17405 T3122 C247 P38111 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 17405 T3122 C247 P38111 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:47457/sfi/z/collection1/. core=collection1
[junit4:junit4]   2> 17405 T3122 C247 P38111 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C249 name=collection1 org.apache.solr.core.SolrCore@760d42f5 url=http://127.0.0.1:47457/sfi/z/collection1 node=127.0.0.1:47457_sfi%2Fz C249_STATE=coll:collection1 core:collection1 props:{shard=shard2, roles=null, core=collection1, base_url=http://127.0.0.1:47457/sfi/z, state=active, node_name=127.0.0.1:47457_sfi%2Fz, collection=collection1, leader=true}
[junit4:junit4]   2> 17411 T3082 C249 P47457 REQ /get {version=2&wt=javabin&getVersions=100&qt=/get&distrib=false} status=0 QTime=1 
[junit4:junit4]   2> 17412 T3083 C249 P47457 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 17412 T3083 C249 P47457 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1
[junit4:junit4]   2> 17414 T3083 C249 P47457 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1163094f lockFactory=org.apache.lucene.store.NativeFSLockFactory@8ab241e),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 17414 T3083 C249 P47457 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 17414 T3083 C249 P47457 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1163094f lockFactory=org.apache.lucene.store.NativeFSLockFactory@8ab241e),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1163094f lockFactory=org.apache.lucene.store.NativeFSLockFactory@8ab241e),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 17415 T3083 C249 P47457 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 17415 T3083 C249 P47457 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1
[junit4:junit4]   2> 17416 T3083 C249 P47457 oass.SolrIndexSearcher.<init> Opening Searcher@3b9d0ad realtime
[junit4:junit4]   2> 17416 T3083 C249 P47457 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 17416 T3083 C249 P47457 /update {commit_end_point=true&softCommit=false&commit=true&waitSearcher=true&version=2&wt=javabin&openSearcher=false} {commit=} 0 4
[junit4:junit4]   2> 17417 T3122 C247 P38111 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 17417 T3122 C247 P38111 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 17418 T3082 C249 P47457 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 17418 T3082 C249 P47457 REQ /replication {command=indexversion&version=2&qt=/replication&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 17419 T3122 C247 P38111 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 17419 T3122 C247 P38111 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 17419 T3122 C247 P38111 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 17421 T3083 C249 P47457 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1
[junit4:junit4]   2> 17421 T3083 C249 P47457 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty1/index
[junit4:junit4]   2> 17422 T3083 C249 P47457 REQ /replication {generation=2&command=filelist&version=2&qt=/replication&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 17422 T3122 C247 P38111 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 17423 T3122 C247 P38111 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index.20130224051506784 forceNew:false
[junit4:junit4]   2> 17423 T3122 C247 P38111 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3
[junit4:junit4]   2> 17423 T3122 C247 P38111 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@5fba394 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2375fe3d) fullCopy=true
[junit4:junit4]   2> 17425 T3085 C249 P47457 REQ /replication {generation=2&command=filecontent&checksum=true&file=segments_2&wt=filestream&qt=/replication} status=0 QTime=0 
[junit4:junit4]   2> 17427 T3122 C247 P38111 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index
[junit4:junit4]   2> 17427 T3122 C247 P38111 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 17427 T3122 C247 P38111 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130224051506784
[junit4:junit4]   2> 17427 T3122 C247 P38111 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3
[junit4:junit4]   2> 17427 T3122 C247 P38111 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3
[junit4:junit4]   2> 17428 T3122 C247 P38111 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index/ new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index.20130224051506784
[junit4:junit4]   2> 17428 T3122 C247 P38111 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5fba394 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2375fe3d),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 17428 T3122 C247 P38111 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 17429 T3122 C247 P38111 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3
[junit4:junit4]   2> 17429 T3122 C247 P38111 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3
[junit4:junit4]   2> 17429 T3122 C247 P38111 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 17429 T3122 C247 P38111 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 17429 T3122 C247 P38111 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 17429 T3122 C247 P38111 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index.20130224051506784
[junit4:junit4]   2> 17430 T3122 C247 P38111 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3
[junit4:junit4]   2> 17430 T3122 C247 P38111 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@5fba394 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2375fe3d),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 17430 T3122 C247 P38111 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 17430 T3122 C247 P38111 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 17431 T3122 C247 P38111 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3
[junit4:junit4]   2> 17431 T3122 C247 P38111 oass.SolrIndexSearcher.<init> Opening Searcher@4ffa3b48 main
[junit4:junit4]   2> 17431 T3121 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ffa3b48 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 17432 T3121 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index
[junit4:junit4]   2> 17432 T3122 C247 P38111 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index.20130224051506784
[junit4:junit4]   2> 17432 T3122 C247 P38111 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty3/index
[junit4:junit4]   2> 17432 T3122 C247 P38111 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 17432 T3122 C247 P38111 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 17432 T3122 C247 P38111 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 17432 T3122 C247 P38111 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 17433 T3122 C247 P38111 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 18336 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18337 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56505/sfi/z",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:56505_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 18339 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"unloadcollection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47457/sfi/z",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47457_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"unloadcollection"}
[junit4:junit4]   2> 18340 T3071 oasc.Overseer$ClusterStateUpdater.createCollection Create collection unloadcollection with numShards 1
[junit4:junit4]   2> 18340 T3071 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 18342 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:38111/sfi/z",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:38111_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 18345 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18345 T3119 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18345 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18345 T3135 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18345 T3104 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 18345 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 19327 T3084 oasc.CoreContainer.registerCore registering core: unloadcollection1
[junit4:junit4]   2> 19328 T3084 oasc.ZkController.register Register replica - core:unloadcollection1 address:http://127.0.0.1:47457/sfi/z collection:unloadcollection shard:shard1
[junit4:junit4]   2> 19328 T3084 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection/leader_elect/shard1/election
[junit4:junit4]   2> 19333 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990006 type:delete cxid:0xa4 zxid:0xc2 txntype:-1 reqpath:n/a Error Path:/solr/collections/unloadcollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/unloadcollection/leaders
[junit4:junit4]   2> 19333 T3084 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 19333 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990006 type:create cxid:0xa5 zxid:0xc3 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 19334 T3084 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 19335 T3084 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 19335 T3084 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:47457/sfi/z/unloadcollection1/
[junit4:junit4]   2> 19335 T3084 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 19335 T3084 oasc.SyncStrategy.syncToMe http://127.0.0.1:47457/sfi/z/unloadcollection1/ has no replicas
[junit4:junit4]   2> 19335 T3084 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:47457/sfi/z/unloadcollection1/
[junit4:junit4]   2> 19336 T3084 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection/leaders/shard1
[junit4:junit4]   2> 19338 T3053 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0b7bb4990006 type:create cxid:0xaf zxid:0xc7 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 19851 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19855 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 19856 T3135 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 19856 T3119 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 19856 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 19855 T3104 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 19856 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 19890 T3084 oasc.ZkController.register We are http://127.0.0.1:47457/sfi/z/unloadcollection1/ and leader is http://127.0.0.1:47457/sfi/z/unloadcollection1/
[junit4:junit4]   2> 19890 T3084 oasc.ZkController.register No LogReplay needed for core=unloadcollection1 baseURL=http://127.0.0.1:47457/sfi/z
[junit4:junit4]   2> 19890 T3084 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 19891 T3084 oasc.ZkController.publish publishing core=unloadcollection1 state=active
[junit4:junit4]   2> 19892 T3084 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19892 T3084 oasc.CoreContainer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/solr.xml
[junit4:junit4]   2> 19898 T3084 oasc.SolrXMLSerializer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361697292677/solr.xml
[junit4:junit4]   2> 19899 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 19900 T3048 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55865/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@20d69282
[junit4:junit4]   2> 19901 T3048 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 19901 T3142 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:55865. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 19901 T3142 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55865, initiating session
[junit4:junit4]   2> 19901 T3050 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:36714
[junit4:junit4]   2> 19901 T3050 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:36714
[junit4:junit4]   2> 19902 T3052 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0b7bb499000d with negotiated timeout 10000 for client /127.0.0.1:36714
[junit4:junit4]   2> 19902 T3142 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:55865, sessionid = 0x13d0b7bb499000d, negotiated timeout = 10000
[junit4:junit4]   2> 19903 T3143 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@20d69282 name:ZooKeeperConnection Watcher:127.0.0.1:55865/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 19903 T3048 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 19904 T3048 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 19905 T3048 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19906 T3048 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 19909 T3099 oasc.CoreContainer.create Creating SolrCore 'unloadcollection2' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170/unloadcollection2
[junit4:junit4]   2> 19909 T3099 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4]   2> 19910 T3099 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 19910 T3099 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4]   2> 19911 T3099 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170/unloadcollection2/'
[junit4:junit4]   2> 19946 T3099 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 19974 T3099 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 19975 T3099 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 19979 T3099 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 20292 T3099 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 20296 T3099 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 20298 T3099 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 20305 T3099 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 20307 T3099 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 20309 T3099 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 20310 T3099 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 20311 T3099 oasc.SolrCore.<init> [unloadcollection2] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361697297170/unloadcollection2/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697309270unloadcollection1_2n/
[junit4:junit4]   2> 20311 T3099 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5176f87c
[junit4:junit4]   2> 20311 T3099 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 20312 T3099 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697309270unloadcollection1_2n forceNew:false
[junit4:junit4]   2> 20312 T3099 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697309270unloadcollection1_2n
[junit4:junit4]   2> 20312 T3099 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697309270unloadcollection1_2n/index/
[junit4:junit4]   2> 20313 T3099 oasc.SolrCore.initIndex WARNING [unloadcollection2] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697309270unloadcollection1_2n/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 20313 T3099 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697309270unloadcollection1_2n/index forceNew:false
[junit4:junit4]   2> 20314 T3099 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@37091614 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a83901f),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 20314 T3099 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 20315 T3099 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697309270unloadcollection1_2n/index
[junit4:junit4]   2> 20316 T3099 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 20316 T3099 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 20316 T3099 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 20317 T3099 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 20317 T3099 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 20317 T3099 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 20317 T3099 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 20318 T3099 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 20318 T3099 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 20320 T3099 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 20322 T3099 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697309270unloadcollection1_2n
[junit4:junit4]   2> 20322 T3099 oass.SolrIndexSearcher.<init> Opening Searcher@90cb18d main
[junit4:junit4]   2> 20323 T3099 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697309270unloadcollection1_2n/tlog
[junit4:junit4]   2> 20323 T3099 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 20323 T3099 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 20325 T3099 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361697289366/1361697309270unloadcollection1_2n
[junit4:junit4]   2> 20326 T3144 oasc.SolrCore.registerSearcher [unloadcollection2] Registered new searcher Searcher@90cb18d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 20326 T3099 oasc.ZkController.publish publishing core=unloadcollection2 state=down
[junit4:junit4]   2> 20326 T3099 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2>  C248_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, core=collection1, base_url=http://127.0.0.1:56505/sfi/z, state=recovering, node_name=127.0.0.1:56505_sfi%2Fz, collection=collection1}
[junit4:junit4]   2> 20909 T3138 C248 P56505 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:53632/sfi/z/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 20909 T3138 C248 P56505 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:56505/sfi/z START replicas=[http://127.0.0.1:53632/sfi/z/collection1/] nUpdates=100
[junit4:junit4]   2> 20910 T3138 C248 P56505 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 20910 T3138 C248 P56505 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 20910 T3138 C248 P56505 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 20910 T3138 C248 P56505 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 20911 T3138 C248 P56505 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 20911 T3138 C248 P56505 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:53632/sfi/z/collection1/. core=collection1
[junit4:junit4]   2> 20911 T3138 C248 P56505 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C250 name=collection1 org.apache.solr.core.SolrCore@6897271e url=http://127.0.0.1:53632/sfi/z/collection1 node=127.0.0.1:53632_sfi%2Fz C250_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, core=collection1, base_url=http://127.0.0.1:53632/sfi/z, state=active, node_name=127.0.0.1:53632_sfi%2Fz, collection=collection1, leader=true}
[junit4:junit4]   2> 20914 T3100 C250 P53632 REQ /get {version=2&wt=javabin&getVersions=100&qt=/get&distrib=false} status=0 QTime=1 
[junit4:junit4]   2> 20916 T3100 C250 P53632 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 20916 T3100 C250 P53632 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2
[junit4:junit4]   2> 20917 T3100 C250 P53632 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@562f3bd3 lockFactory=org.apache.lucene.store.NativeFSLockFactory@13bd45c5),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 20917 T3100 C250 P53632 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 20917 T3100 C250 P53632 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@562f3bd3 lockFactory=org.apache.lucene.store.NativeFSLockFactory@13bd45c5),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@562f3bd3 lockFactory=org.apache.lucene.store.NativeFSLockFactory@13bd45c5),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 20918 T3100 C250 P53632 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 20918 T3100 C250 P53632 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2
[junit4:junit4]   2> 20918 T3100 C250 P53632 oass.SolrIndexSearcher.<init> Opening Searcher@1bbd9c1f realtime
[junit4:junit4]   2> 20918 T3100 C250 P53632 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 20919 T3100 C250 P53632 /update {commit_end_point=true&softCommit=false&commit=true&waitSearcher=true&version=2&wt=javabin&openSearcher=false} {commit=} 0 3
[junit4:junit4]   2> 20919 T3138 C248 P56505 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 20919 T3138 C248 P56505 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 20921 T3098 C250 P53632 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 20921 T3098 C250 P53632 REQ /replication {command=indexversion&version=2&qt=/replication&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 20921 T3138 C248 P56505 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 20921 T3138 C248 P56505 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 20922 T3138 C248 P56505 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 20923 T3100 C250 P53632 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2
[junit4:junit4]   2> 20923 T3100 C250 P53632 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty2/index
[junit4:junit4]   2> 20924 T3100 C250 P53632 REQ /replication {generation=2&command=filelist&version=2&qt=/replication&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 20924 T3138 C248 P56505 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 20925 T3138 C248 P56505 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index.20130224051510286 forceNew:false
[junit4:junit4]   2> 20925 T3138 C248 P56505 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4
[junit4:junit4]   2> 20925 T3138 C248 P56505 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@552e060a lockFactory=org.apache.lucene.store.NativeFSLockFactory@55424456) fullCopy=true
[junit4:junit4]   2> 20927 T3097 C250 P53632 REQ /replication {generation=2&command=filecontent&checksum=true&file=segments_2&wt=filestream&qt=/replication} status=0 QTime=0 
[junit4:junit4]   2> 20928 T3138 C248 P56505 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index
[junit4:junit4]   2> 20929 T3138 C248 P56505 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 20929 T3138 C248 P56505 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130224051510286
[junit4:junit4]   2> 20929 T3138 C248 P56505 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4
[junit4:junit4]   2> 20930 T3138 C248 P56505 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4
[junit4:junit4]   2> 20930 T3138 C248 P56505 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index/ new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index.20130224051510286
[junit4:junit4]   2> 20931 T3138 C248 P56505 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@552e060a lockFactory=org.apache.lucene.store.NativeFSLockFactory@55424456),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 20931 T3138 C248 P56505 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 20931 T3138 C248 P56505 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4
[junit4:junit4]   2> 20932 T3138 C248 P56505 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4
[junit4:junit4]   2> 20932 T3138 C248 P56505 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 20932 T3138 C248 P56505 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 20932 T3138 C248 P56505 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 20933 T3138 C248 P56505 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index.20130224051510286
[junit4:junit4]   2> 20933 T3138 C248 P56505 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4
[junit4:junit4]   2> 20934 T3138 C248 P56505 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@552e060a lockFactory=org.apache.lucene.store.NativeFSLockFactory@55424456),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 20934 T3138 C248 P56505 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 20934 T3138 C248 P56505 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 20934 T3138 C248 P56505 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4
[junit4:junit4]   2> 20935 T3138 C248 P56505 oass.SolrIndexSearcher.<init> Opening Searcher@1f53f93e main
[junit4:junit4]   2> 20935 T3137 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f53f93e main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 20935 T3137 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index
[junit4:junit4]   2> 20935 T3138 C248 P56505 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index.20130224051510286
[junit4:junit4]   2> 20936 T3138 C248 P56505 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361697289366/jetty4/index
[junit4:junit4]   2> 20936 T3138 C248 P56505 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 20936 T3138 C248 P56505 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 20936 T3138 C248 P56505 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 20936 T3138 C248 P56505 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 20937 T3138 C248 P56505 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 21360 T3071 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21361 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"unloadcollection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47457/sfi/z",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47457_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "collection":"unloadcollection"}
[junit4:junit4]   2> 21363 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"unloadcollection2",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:53632/sfi/z",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:53632_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"unloadcollection"}
[junit4:junit4]   2> 21363 T3071 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 21363 T3071 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 21365 T3071 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:56505/sfi/z",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:56505_sfi%2Fz",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "collection":"collection1"}
[junit4:junit4]   2> 21368 T3135 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21368 T3119 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21368 T3104 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21368 T3076 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21368 T3143 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21368 T3089 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 21368 T3070 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   2> 22327 T3099 oasc.CoreContainer.registerCore registering core: unloadcollection2
[junit4:junit4]   2> 22328 T3099 oasc.ZkController.register Register replica - core:unloadcollection2 address:http://127.0.0.1:53632/sfi/z collection:unloadcollection shard:shard1
[junit4:junit4]   2> 22330 T3099 oasc.ZkController.register We are http://127.0.0.1:53632/sfi/z/unloadcollection2/ and leader is http://127.0.0.1:47457/sfi/z/unloadcollection1/
[junit4:junit4]   2> 22330 T3099 oasc.ZkController.register No LogReplay needed for core=unloadcollection2 baseURL=http://127.0.0.1:53632/sfi/z
[junit4:junit4]   2> 22330 T3099 oasc.ZkController.checkRecovery Core needs to recover:unloadcollection2
[junit4:junit4]   2> 22331 T3099 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C251 name=unloadcollection2 org.apache.solr.core.SolrCore@39a02140 url=http://127.0.0.1:53632/sfi/z/unloadcollection2 node=127.0.0.1:53632_sfi%2Fz C251_STATE=coll:unloadcollection core:unloadcollection2 props:{shard=null, roles=null, core=unloadcollection2, base_url=http://127.0.0.1:53632/sfi/z, state=down, node_name=127.0.0.1:53632_sfi%2Fz, collection=unloadcollection}
[junit4:junit4]   2> 22331 T3147 C251 P53632 oasc.RecoveryStrategy.run Starting recovery process.  core=unloadcollection2 recoveringAfterStartup=true
[junit4:junit4]   2> 22331 T3099 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22332 T3147 C251 P53632 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 22332 T3099 oasc.CoreContainer.persistFile Persisting cores 

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

tributedZkTest.testUnloadShardAndCollection(UnloadDistributedZkTest.java:120)
[junit4:junit4]    > 	at org.apache.solr.cloud.UnloadDistributedZkTest.doTest(UnloadDistributedZkTest.java:73)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:794)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:474)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 151967 T3048 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 151980 T3047 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4:junit4]   2> 152638 T3051 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> NOTE: test params are: codec=Lucene42: {text=PostingsFormat(name=NestedPulsing), range_facet_sl=PostingsFormat(name=Memory doPackFST= true), a_si=MockFixedIntBlock(blockSize=861), _version_=MockFixedIntBlock(blockSize=861), other_tl1=MockFixedIntBlock(blockSize=861), timestamp=PostingsFormat(name=Direct), multiDefault=PostingsFormat(name=Direct), a_t=PostingsFormat(name=Direct), range_facet_si=PostingsFormat(name=Direct), range_facet_l=PostingsFormat(name=Direct), id=PostingsFormat(name=Memory doPackFST= true), intDefault=MockFixedIntBlock(blockSize=861)}, docValues:{timestamp=DocValuesFormat(name=Asserting)}, sim=DefaultSimilarity, locale=hu_HU, timezone=Brazil/Acre
[junit4:junit4]   2> NOTE: Linux 3.2.0-38-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=2,free=95909184,total=177037312
[junit4:junit4]   2> NOTE: All tests run in this JVM: [SystemInfoHandlerTest, DirectUpdateHandlerOptimizeTest, DateMathParserTest, UniqFieldsUpdateProcessorFactoryTest, TestComponentsName, TestIndexSearcher, DistributedTermsComponentTest, DistributedSpellCheckComponentTest, SolrIndexSplitterTest, TestCoreContainer, TestExtendedDismaxParser, ZkCLITest, BinaryUpdateRequestHandlerTest, TestDefaultSimilarityFactory, TestLFUCache, TestFastWriter, SampleTest, SuggesterWFSTTest, UpdateRequestProcessorFactoryTest, TestCollationField, TestSolrJ, SliceStateUpdateTest, TestGroupingSearch, FieldAnalysisRequestHandlerTest, RequestHandlersTest, TestReload, SolrCmdDistributorTest, TestQuerySenderNoQuery, SolrRequestParserTest, TestFaceting, TestFastLRUCache, TestCharFilters, SearchHandlerTest, TestConfig, MoreLikeThisHandlerTest, TestFastOutputStream, SchemaVersionSpecificBehaviorTest, TestQueryTypes, TestPHPSerializedResponseWriter, ShardRoutingCustomTest, IndexBasedSpellCheckerTest, CopyFieldTest, SolrCoreCheckLockOnStartupTest, MBeansHandlerTest, SpatialFilterTest, JSONWriterTest, TestNumberUtils, TestWordDelimiterFilterFactory, TestStressVersions, TestIndexingPerformance, AutoCommitTest, ZkControllerTest, TestBadConfig, DistanceFunctionTest, DirectSolrConnectionTest, TestLMDirichletSimilarityFactory, CurrencyFieldTest, ChaosMonkeyNothingIsSafeTest, TestStressLucene, PathHierarchyTokenizerFactoryTest, BasicDistributedZkTest, RecoveryZkTest, TestReplicationHandler, LeaderElectionIntegrationTest, TestDistributedSearch, SpellCheckCollatorTest, SoftAutoCommitTest, ZkSolrClientTest, TestRecovery, TestJoin, QueryElevationComponentTest, CoreAdminHandlerTest, TestFunctionQuery, PolyFieldTest, TestPseudoReturnFields, TermsComponentTest, XsltUpdateRequestHandlerTest, JsonLoaderTest, IndexSchemaRuntimeFieldTest, SolrPluginUtilsTest, ReturnFieldsTest, SuggesterFSTTest, TestQueryUtils, TestSearchPerf, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, NoCacheHeaderTest, PrimitiveFieldTypeTest, BadComponentTest, TestBinaryField, HighlighterConfigTest, TestPropInjectDefaults, TestXIncludeConfig, MultiTermTest, TestSolrCoreProperties, EchoParamsTest, SpellPossibilityIteratorTest, TestPhraseSuggestions, TestPluginEnable, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, PluginInfoTest, PrimUtilsTest, ResourceLoaderTest, TestSystemIdResolver, DateFieldTest, TestSuggestSpellingConverter, ChaosMonkeySafeLeaderTest, TestZkChroot, UnloadDistributedZkTest]
[junit4:junit4] Completed on J0 in 153.00s, 1 test, 1 failure <<< FAILURES!

[...truncated 10 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:381: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:361: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:183: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:447: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1202: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:865: There were test failures: 254 suites, 1063 tests, 1 failure, 8 ignored (3 assumptions)

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



Mime
View raw message