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-4.x-Linux (64bit/jdk1.8.0-ea-b65) - Build # 4397 - Failure!
Date Fri, 22 Feb 2013 05:34:30 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/4397/
Java: 64bit/jdk1.8.0-ea-b65 -XX:+UseG1GC

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([D34A32A41467D2AF:52ACBCBC6338B293]: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 9596 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.UnloadDistributedZkTest
[junit4:junit4]   2> 0 T2013 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4]   2> 2 T2013 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336
[junit4:junit4]   2> 3 T2013 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 3 T2014 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 4 T2014 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 4 T2014 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 4 T2014 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 4 T2014 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 5 T2014 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 103 T2013 oasc.ZkTestServer.run start zk server on port:50663
[junit4:junit4]   2> 104 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@28ab3267
[junit4:junit4]   2> 104 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 105 T2019 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 105 T2019 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 105 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56513
[junit4:junit4]   2> 105 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56513
[junit4:junit4]   2> 106 T2017 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 109 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b0000 with negotiated timeout 10000 for client /127.0.0.1:56513
[junit4:junit4]   2> 109 T2019 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b0000, negotiated timeout = 10000
[junit4:junit4]   2> 109 T2020 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@28ab3267 name:ZooKeeperConnection Watcher:127.0.0.1:50663 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 109 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 110 T2013 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 112 T2018 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0062ea4b0000
[junit4:junit4]   2> 112 T2020 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 112 T2015 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56513 which had sessionid 0x13d0062ea4b0000
[junit4:junit4]   2> 112 T2013 oaz.ZooKeeper.close Session: 0x13d0062ea4b0000 closed
[junit4:junit4]   2> 113 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@3f1bc613
[junit4:junit4]   2> 113 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 114 T2021 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 114 T2021 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 114 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56514
[junit4:junit4]   2> 114 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56514
[junit4:junit4]   2> 115 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b0001 with negotiated timeout 10000 for client /127.0.0.1:56514
[junit4:junit4]   2> 115 T2021 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b0001, negotiated timeout = 10000
[junit4:junit4]   2> 115 T2022 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3f1bc613 name:ZooKeeperConnection Watcher:127.0.0.1:50663/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 115 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 116 T2013 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 117 T2013 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 119 T2013 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 120 T2013 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 121 T2013 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 122 T2013 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 124 T2013 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 125 T2013 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 127 T2013 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 127 T2013 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 129 T2013 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 129 T2013 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 131 T2013 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 131 T2013 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 133 T2013 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 133 T2013 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 135 T2013 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 135 T2013 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 137 T2013 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 137 T2013 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 139 T2013 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 140 T2013 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 141 T2018 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0062ea4b0001
[junit4:junit4]   2> 142 T2022 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 142 T2015 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56514 which had sessionid 0x13d0062ea4b0001
[junit4:junit4]   2> 142 T2013 oaz.ZooKeeper.close Session: 0x13d0062ea4b0001 closed
[junit4:junit4]   2> 203 T2013 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 206 T2013 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:33694
[junit4:junit4]   2> 207 T2013 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 207 T2013 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 208 T2013 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361511115477
[junit4:junit4]   2> 208 T2013 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361511115477/solr.xml
[junit4:junit4]   2> 208 T2013 oasc.CoreContainer.<init> New CoreContainer 1931887627
[junit4:junit4]   2> 209 T2013 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361511115477/'
[junit4:junit4]   2> 209 T2013 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361511115477/'
[junit4:junit4]   2> 224 T2013 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 224 T2013 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 225 T2013 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 225 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 225 T2013 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 226 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 226 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 226 T2013 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 227 T2013 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 227 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 233 T2013 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 243 T2013 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:50663/solr
[junit4:junit4]   2> 243 T2013 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 244 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@4fc1db47
[junit4:junit4]   2> 244 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 244 T2032 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 245 T2032 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 245 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56515
[junit4:junit4]   2> 245 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56515
[junit4:junit4]   2> 246 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b0002 with negotiated timeout 20000 for client /127.0.0.1:56515
[junit4:junit4]   2> 246 T2032 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b0002, negotiated timeout = 20000
[junit4:junit4]   2> 246 T2033 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4fc1db47 name:ZooKeeperConnection Watcher:127.0.0.1:50663 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 246 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 247 T2018 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0062ea4b0002
[junit4:junit4]   2> 247 T2033 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 247 T2015 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56515 which had sessionid 0x13d0062ea4b0002
[junit4:junit4]   2> 247 T2013 oaz.ZooKeeper.close Session: 0x13d0062ea4b0002 closed
[junit4:junit4]   2> 248 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 250 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@606f351b
[junit4:junit4]   2> 251 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 251 T2034 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 251 T2034 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 251 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56516
[junit4:junit4]   2> 251 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56516
[junit4:junit4]   2> 252 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b0003 with negotiated timeout 20000 for client /127.0.0.1:56516
[junit4:junit4]   2> 252 T2034 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b0003, negotiated timeout = 20000
[junit4:junit4]   2> 252 T2035 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@606f351b name:ZooKeeperConnection Watcher:127.0.0.1:50663/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 252 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 253 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 254 T2013 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 255 T2013 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33694_
[junit4:junit4]   2> 255 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:33694_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:33694_
[junit4:junit4]   2> 256 T2013 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:33694_
[junit4:junit4]   2> 257 T2013 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 259 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 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> 260 T2013 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 261 T2013 oasc.Overseer.start Overseer (id=89227992454856707-127.0.0.1:33694_-n_0000000000) starting
[junit4:junit4]   2> 261 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 262 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 262 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 263 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 263 T2037 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 263 T2013 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 265 T2013 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 266 T2036 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 268 T2038 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361511115477/collection1
[junit4:junit4]   2> 269 T2038 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 269 T2038 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 269 T2038 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 270 T2038 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361511115477/collection1/'
[junit4:junit4]   2> 270 T2038 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361511115477/collection1/lib/README' to classloader
[junit4:junit4]   2> 270 T2038 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361511115477/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 291 T2038 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 319 T2038 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 320 T2038 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 323 T2038 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 632 T2038 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 636 T2038 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 638 T2038 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 645 T2038 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 647 T2038 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 649 T2038 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 650 T2038 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 651 T2038 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-controljetty-1361511115477/collection1/, dataDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/control/data/
[junit4:junit4]   2> 651 T2038 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@9530a8a
[junit4:junit4]   2> 651 T2038 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 652 T2038 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/control/data forceNew:false
[junit4:junit4]   2> 652 T2038 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/control/data
[junit4:junit4]   2> 652 T2038 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/control/data/index/
[junit4:junit4]   2> 652 T2038 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 652 T2038 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/control/data/index forceNew:false
[junit4:junit4]   2> 653 T2038 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@10dd0cdf lockFactory=org.apache.lucene.store.NativeFSLockFactory@48459499; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 654 T2038 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 654 T2038 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/control/data/index
[junit4:junit4]   2> 655 T2038 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 655 T2038 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 655 T2038 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 656 T2038 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 656 T2038 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 656 T2038 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 656 T2038 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 656 T2038 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 657 T2038 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 659 T2038 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 660 T2038 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/control/data
[junit4:junit4]   2> 661 T2038 oass.SolrIndexSearcher.<init> Opening Searcher@3e8424e2 main
[junit4:junit4]   2> 661 T2038 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/control/data/tlog
[junit4:junit4]   2> 661 T2038 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 662 T2038 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 663 T2038 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/control/data
[junit4:junit4]   2> 663 T2039 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3e8424e2 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 664 T2038 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 664 T2038 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 664 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 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> 1768 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1768 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:33694",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:33694_",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 1768 T2036 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 1769 T2036 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 1769 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 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> 1772 T2035 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> 2666 T2038 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2666 T2038 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:33694 collection:control_collection shard:shard1
[junit4:junit4]   2> 2667 T2038 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 2671 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 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> 2671 T2038 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2672 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2673 T2038 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2673 T2038 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2673 T2038 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:33694/collection1/
[junit4:junit4]   2> 2673 T2038 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2673 T2038 oasc.SyncStrategy.syncToMe http://127.0.0.1:33694/collection1/ has no replicas
[junit4:junit4]   2> 2674 T2038 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:33694/collection1/
[junit4:junit4]   2> 2674 T2038 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 2676 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3274 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3278 T2035 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> 3329 T2038 oasc.ZkController.register We are http://127.0.0.1:33694/collection1/ and leader is http://127.0.0.1:33694/collection1/
[junit4:junit4]   2> 3329 T2038 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:33694
[junit4:junit4]   2> 3329 T2038 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3329 T2038 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 3329 T2038 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3331 T2038 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3331 T2013 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3332 T2013 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3332 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3335 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 3335 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@55b7318e
[junit4:junit4]   2> 3336 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3336 T2040 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3336 T2040 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 3336 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56524
[junit4:junit4]   2> 3337 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56524
[junit4:junit4]   2> 3337 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b0004 with negotiated timeout 10000 for client /127.0.0.1:56524
[junit4:junit4]   2> 3337 T2040 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b0004, negotiated timeout = 10000
[junit4:junit4]   2> 3338 T2041 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@55b7318e name:ZooKeeperConnection Watcher:127.0.0.1:50663/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3338 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3339 T2013 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3340 T2013 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 3385 T2013 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 3386 T2013 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:40361
[junit4:junit4]   2> 3387 T2013 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3387 T2013 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3388 T2013 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674
[junit4:junit4]   2> 3388 T2013 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/solr.xml
[junit4:junit4]   2> 3388 T2013 oasc.CoreContainer.<init> New CoreContainer 1739736376
[junit4:junit4]   2> 3389 T2013 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/'
[junit4:junit4]   2> 3389 T2013 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/'
[junit4:junit4]   2> 3403 T2013 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 3403 T2013 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3403 T2013 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 3404 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3404 T2013 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3404 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3405 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3405 T2013 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3405 T2013 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3405 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 3410 T2013 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3419 T2013 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:50663/solr
[junit4:junit4]   2> 3419 T2013 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 3420 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@4a562c66
[junit4:junit4]   2> 3420 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3421 T2051 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3421 T2051 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 3421 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56525
[junit4:junit4]   2> 3421 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56525
[junit4:junit4]   2> 3422 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b0005 with negotiated timeout 20000 for client /127.0.0.1:56525
[junit4:junit4]   2> 3422 T2051 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b0005, negotiated timeout = 20000
[junit4:junit4]   2> 3422 T2052 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4a562c66 name:ZooKeeperConnection Watcher:127.0.0.1:50663 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3422 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3423 T2018 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0062ea4b0005
[junit4:junit4]   2> 3424 T2052 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 3424 T2015 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56525 which had sessionid 0x13d0062ea4b0005
[junit4:junit4]   2> 3424 T2013 oaz.ZooKeeper.close Session: 0x13d0062ea4b0005 closed
[junit4:junit4]   2> 3424 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 3427 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@2da85dd7
[junit4:junit4]   2> 3427 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3427 T2053 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3428 T2053 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 3428 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56526
[junit4:junit4]   2> 3428 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56526
[junit4:junit4]   2> 3429 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b0006 with negotiated timeout 20000 for client /127.0.0.1:56526
[junit4:junit4]   2> 3429 T2053 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b0006, negotiated timeout = 20000
[junit4:junit4]   2> 3429 T2054 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2da85dd7 name:ZooKeeperConnection Watcher:127.0.0.1:50663/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3429 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3430 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3430 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3431 T2013 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4433 T2013 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40361_
[junit4:junit4]   2> 4434 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0006 type:delete cxid:0x9 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:40361_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:40361_
[junit4:junit4]   2> 4434 T2013 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:40361_
[junit4:junit4]   2> 4437 T2041 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4437 T2035 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4437 T2035 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> 4437 T2054 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4441 T2055 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/collection1
[junit4:junit4]   2> 4441 T2055 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4442 T2055 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4442 T2055 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4443 T2055 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/collection1/'
[junit4:junit4]   2> 4443 T2055 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/collection1/lib/README' to classloader
[junit4:junit4]   2> 4444 T2055 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4464 T2055 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 4489 T2055 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4490 T2055 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4493 T2055 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4776 T2055 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4781 T2055 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4782 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4783 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:33694",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:33694_",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 4783 T2055 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4785 T2054 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> 4785 T2035 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> 4785 T2041 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> 4791 T2055 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4794 T2055 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4796 T2055 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4797 T2055 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4798 T2055 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/collection1/, dataDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1/
[junit4:junit4]   2> 4798 T2055 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@9530a8a
[junit4:junit4]   2> 4798 T2055 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 4799 T2055 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1 forceNew:false
[junit4:junit4]   2> 4799 T2055 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1
[junit4:junit4]   2> 4799 T2055 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1/index/
[junit4:junit4]   2> 4799 T2055 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4799 T2055 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1/index forceNew:false
[junit4:junit4]   2> 4800 T2055 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@243b4189 lockFactory=org.apache.lucene.store.NativeFSLockFactory@29464568; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4800 T2055 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 4801 T2055 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1/index
[junit4:junit4]   2> 4802 T2055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4802 T2055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4802 T2055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4803 T2055 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4803 T2055 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4803 T2055 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4803 T2055 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4803 T2055 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4804 T2055 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4806 T2055 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4808 T2055 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1
[junit4:junit4]   2> 4808 T2055 oass.SolrIndexSearcher.<init> Opening Searcher@64b7f035 main
[junit4:junit4]   2> 4808 T2055 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1/tlog
[junit4:junit4]   2> 4809 T2055 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4809 T2055 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4812 T2056 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@64b7f035 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4814 T2055 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 4814 T2055 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6289 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6289 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:40361",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:40361_",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 6289 T2036 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 6290 T2036 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 6292 T2035 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> 6292 T2054 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> 6292 T2041 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> 6815 T2055 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 6815 T2055 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:40361 collection:collection1 shard:shard2
[junit4:junit4]   2> 6816 T2055 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 6820 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0006 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> 6821 T2055 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 6821 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0006 type:create cxid:0x47 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6822 T2055 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 6823 T2055 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 6823 T2055 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:40361/collection1/
[junit4:junit4]   2> 6823 T2055 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 6823 T2055 oasc.SyncStrategy.syncToMe http://127.0.0.1:40361/collection1/ has no replicas
[junit4:junit4]   2> 6823 T2055 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:40361/collection1/
[junit4:junit4]   2> 6823 T2055 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 6826 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0006 type:create cxid:0x51 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7795 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7800 T2054 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> 7800 T2041 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> 7800 T2035 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> 7829 T2055 oasc.ZkController.register We are http://127.0.0.1:40361/collection1/ and leader is http://127.0.0.1:40361/collection1/
[junit4:junit4]   2> 7829 T2055 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:40361
[junit4:junit4]   2> 7830 T2055 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 7830 T2055 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 7830 T2055 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7831 T2055 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7832 T2013 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 7832 T2013 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7833 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7891 T2013 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 7893 T2013 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:33717
[junit4:junit4]   2> 7893 T2013 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 7894 T2013 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7894 T2013 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171
[junit4:junit4]   2> 7894 T2013 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171/solr.xml
[junit4:junit4]   2> 7895 T2013 oasc.CoreContainer.<init> New CoreContainer 1112627508
[junit4:junit4]   2> 7895 T2013 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171/'
[junit4:junit4]   2> 7895 T2013 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171/'
[junit4:junit4]   2> 7910 T2013 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 7911 T2013 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 7911 T2013 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 7911 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 7912 T2013 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 7912 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 7912 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 7913 T2013 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 7913 T2013 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 7913 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 7920 T2013 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 7931 T2013 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:50663/solr
[junit4:junit4]   2> 7932 T2013 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 7932 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@5ebfd251
[junit4:junit4]   2> 7933 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7933 T2066 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 7934 T2066 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 7934 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56535
[junit4:junit4]   2> 7934 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56535
[junit4:junit4]   2> 7935 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b0007 with negotiated timeout 20000 for client /127.0.0.1:56535
[junit4:junit4]   2> 7935 T2066 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b0007, negotiated timeout = 20000
[junit4:junit4]   2> 7935 T2067 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5ebfd251 name:ZooKeeperConnection Watcher:127.0.0.1:50663 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 7935 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 7936 T2018 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0062ea4b0007
[junit4:junit4]   2> 7937 T2067 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 7937 T2015 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56535 which had sessionid 0x13d0062ea4b0007
[junit4:junit4]   2> 7937 T2013 oaz.ZooKeeper.close Session: 0x13d0062ea4b0007 closed
[junit4:junit4]   2> 7937 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 7940 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@3fb589b1
[junit4:junit4]   2> 7940 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7940 T2068 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 7941 T2068 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 7941 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56536
[junit4:junit4]   2> 7941 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56536
[junit4:junit4]   2> 7942 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b0008 with negotiated timeout 20000 for client /127.0.0.1:56536
[junit4:junit4]   2> 7942 T2068 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b0008, negotiated timeout = 20000
[junit4:junit4]   2> 7942 T2069 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3fb589b1 name:ZooKeeperConnection Watcher:127.0.0.1:50663/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 7942 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 7943 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7943 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7944 T2013 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8946 T2013 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33717_
[junit4:junit4]   2> 8946 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0008 type:delete cxid:0x9 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:33717_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:33717_
[junit4:junit4]   2> 8948 T2013 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:33717_
[junit4:junit4]   2> 8950 T2035 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> 8950 T2054 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 8950 T2041 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 8950 T2054 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> 8950 T2069 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 8951 T2041 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> 8951 T2035 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 8954 T2070 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171/collection1
[junit4:junit4]   2> 8955 T2070 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8955 T2070 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8955 T2070 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8956 T2070 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171/collection1/'
[junit4:junit4]   2> 8957 T2070 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171/collection1/lib/README' to classloader
[junit4:junit4]   2> 8957 T2070 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8977 T2070 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 9002 T2070 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9003 T2070 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9006 T2070 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9289 T2070 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9293 T2070 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9295 T2070 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9304 T2070 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9304 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9304 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:40361",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:40361_",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 9306 T2041 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> 9307 T2070 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9307 T2069 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> 9307 T2035 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> 9306 T2054 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> 9310 T2070 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9311 T2070 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9311 T2070 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171/collection1/, dataDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2/
[junit4:junit4]   2> 9312 T2070 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@9530a8a
[junit4:junit4]   2> 9312 T2070 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 9313 T2070 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2 forceNew:false
[junit4:junit4]   2> 9313 T2070 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2
[junit4:junit4]   2> 9313 T2070 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2/index/
[junit4:junit4]   2> 9313 T2070 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 9313 T2070 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2/index forceNew:false
[junit4:junit4]   2> 9314 T2070 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@506736b lockFactory=org.apache.lucene.store.NativeFSLockFactory@7b6cfb73; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9315 T2070 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 9315 T2070 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2/index
[junit4:junit4]   2> 9316 T2070 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 9316 T2070 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 9317 T2070 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 9317 T2070 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9317 T2070 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9317 T2070 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 9318 T2070 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 9318 T2070 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9318 T2070 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9320 T2070 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9322 T2070 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2
[junit4:junit4]   2> 9322 T2070 oass.SolrIndexSearcher.<init> Opening Searcher@3cd57de4 main
[junit4:junit4]   2> 9323 T2070 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2/tlog
[junit4:junit4]   2> 9323 T2070 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9323 T2070 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9326 T2071 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3cd57de4 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9327 T2070 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 9327 T2070 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 10810 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10811 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:33717",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:33717_",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 10811 T2036 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 10811 T2036 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 10819 T2041 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> 10819 T2069 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> 10819 T2054 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> 10819 T2035 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> 11328 T2070 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 11329 T2070 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:33717 collection:collection1 shard:shard1
[junit4:junit4]   2> 11329 T2070 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 11380 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0008 type:delete cxid:0x45 zxid:0x7e txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   2> 11400 T2070 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 11400 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0008 type:create cxid:0x46 zxid:0x7f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11414 T2070 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 11414 T2070 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 11414 T2070 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:33717/collection1/
[junit4:junit4]   2> 11415 T2070 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 11415 T2070 oasc.SyncStrategy.syncToMe http://127.0.0.1:33717/collection1/ has no replicas
[junit4:junit4]   2> 11415 T2070 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:33717/collection1/
[junit4:junit4]   2> 11415 T2070 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 11430 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0008 type:create cxid:0x4f zxid:0x82 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12323 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12361 T2054 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> 12361 T2041 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> 12361 T2069 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> 12361 T2035 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> 12383 T2070 oasc.ZkController.register We are http://127.0.0.1:33717/collection1/ and leader is http://127.0.0.1:33717/collection1/
[junit4:junit4]   2> 12383 T2070 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:33717
[junit4:junit4]   2> 12383 T2070 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 12384 T2070 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 12384 T2070 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12391 T2070 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12392 T2013 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 12393 T2013 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 12394 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12452 T2013 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 12453 T2013 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46416
[junit4:junit4]   2> 12454 T2013 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 12455 T2013 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 12455 T2013 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361511127732
[junit4:junit4]   2> 12456 T2013 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361511127732/solr.xml
[junit4:junit4]   2> 12456 T2013 oasc.CoreContainer.<init> New CoreContainer 1658838475
[junit4:junit4]   2> 12456 T2013 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361511127732/'
[junit4:junit4]   2> 12457 T2013 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361511127732/'
[junit4:junit4]   2> 12475 T2013 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 12476 T2013 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 12476 T2013 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 12476 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 12476 T2013 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 12477 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 12477 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 12477 T2013 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 12478 T2013 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 12478 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 12485 T2013 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 12499 T2013 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:50663/solr
[junit4:junit4]   2> 12499 T2013 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 12499 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@3cc5207a
[junit4:junit4]   2> 12500 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12501 T2081 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12501 T2081 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 12501 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56626
[junit4:junit4]   2> 12502 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56626
[junit4:junit4]   2> 12503 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b0009 with negotiated timeout 20000 for client /127.0.0.1:56626
[junit4:junit4]   2> 12503 T2081 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b0009, negotiated timeout = 20000
[junit4:junit4]   2> 12503 T2082 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3cc5207a name:ZooKeeperConnection Watcher:127.0.0.1:50663 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12503 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12505 T2018 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0062ea4b0009
[junit4:junit4]   2> 12505 T2015 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56626 which had sessionid 0x13d0062ea4b0009
[junit4:junit4]   2> 12506 T2082 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 12506 T2013 oaz.ZooKeeper.close Session: 0x13d0062ea4b0009 closed
[junit4:junit4]   2> 12507 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 12510 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@6660e16f
[junit4:junit4]   2> 12512 T2083 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12511 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12512 T2083 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 12512 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56628
[junit4:junit4]   2> 12512 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56628
[junit4:junit4]   2> 12513 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b000a with negotiated timeout 20000 for client /127.0.0.1:56628
[junit4:junit4]   2> 12513 T2083 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b000a, negotiated timeout = 20000
[junit4:junit4]   2> 12513 T2084 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6660e16f name:ZooKeeperConnection Watcher:127.0.0.1:50663/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12513 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12515 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b000a type:create cxid:0x1 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12515 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b000a type:create cxid:0x2 zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12516 T2013 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 13518 T2013 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46416_
[junit4:junit4]   2> 13519 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b000a type:delete cxid:0x9 zxid:0x91 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:46416_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:46416_
[junit4:junit4]   2> 13519 T2013 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46416_
[junit4:junit4]   2> 13521 T2054 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> 13521 T2041 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> 13521 T2035 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> 13523 T2069 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13524 T2069 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> 13524 T2084 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13524 T2054 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13524 T2035 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13525 T2041 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13529 T2085 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361511127732/collection1
[junit4:junit4]   2> 13529 T2085 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 13530 T2085 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 13530 T2085 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 13531 T2085 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361511127732/collection1/'
[junit4:junit4]   2> 13531 T2085 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361511127732/collection1/lib/README' to classloader
[junit4:junit4]   2> 13532 T2085 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361511127732/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 13551 T2085 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 13597 T2085 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 13598 T2085 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 13601 T2085 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 13867 T2085 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 13871 T2085 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 13873 T2085 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 13881 T2085 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 13884 T2085 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 13886 T2085 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 13886 T2085 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 13887 T2085 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty3-1361511127732/collection1/, dataDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/
[junit4:junit4]   2> 13887 T2085 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@9530a8a
[junit4:junit4]   2> 13887 T2085 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 13888 T2085 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3 forceNew:false
[junit4:junit4]   2> 13888 T2085 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3
[junit4:junit4]   2> 13888 T2085 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index/
[junit4:junit4]   2> 13888 T2085 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 13889 T2085 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index forceNew:false
[junit4:junit4]   2> 13889 T2085 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@775ff624 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7b7c667a; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13890 T2085 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 13890 T2085 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index
[junit4:junit4]   2> 13891 T2085 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 13891 T2085 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 13892 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13892 T2085 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 13892 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:33717",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:33717_",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 13892 T2085 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 13893 T2085 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 13893 T2085 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 13893 T2085 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 13893 T2085 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 13893 T2085 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 13895 T2069 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> 13895 T2054 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> 13895 T2084 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> 13895 T2035 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> 13895 T2041 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> 13897 T2085 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 13898 T2085 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3
[junit4:junit4]   2> 13899 T2085 oass.SolrIndexSearcher.<init> Opening Searcher@7e6f0390 main
[junit4:junit4]   2> 13899 T2085 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/tlog
[junit4:junit4]   2> 13899 T2085 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 13900 T2085 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 13902 T2086 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e6f0390 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 13903 T2085 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 13903 T2085 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 15398 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15399 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:46416",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:46416_",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 15399 T2036 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 15399 T2036 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 15402 T2069 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> 15402 T2035 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> 15402 T2084 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> 15402 T2041 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> 15402 T2054 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> 15905 T2085 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 15905 T2085 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:46416 collection:collection1 shard:shard2
[junit4:junit4]   2> 15907 T2085 oasc.ZkController.register We are http://127.0.0.1:46416/collection1/ and leader is http://127.0.0.1:40361/collection1/
[junit4:junit4]   2> 15908 T2085 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:46416
[junit4:junit4]   2> 15908 T2085 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 15908 T2085 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C218 name=collection1 org.apache.solr.core.SolrCore@7169cada url=http://127.0.0.1:46416/collection1 node=127.0.0.1:46416_ C218_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:46416, roles=null, node_name=127.0.0.1:46416_, collection=collection1, state=down, shard=null, core=collection1}
[junit4:junit4]   2> 15908 T2087 C218 P46416 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 15909 T2087 C218 P46416 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 15909 T2085 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15909 T2087 C218 P46416 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 15909 T2087 C218 P46416 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 15910 T2087 C218 P46416 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 15909 T2013 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 15910 T2013 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 15911 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 15976 T2013 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 15978 T2013 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:36680
[junit4:junit4]   2> 15979 T2013 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 15979 T2013 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 15979 T2013 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361511131247
[junit4:junit4]   2> 15980 T2013 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361511131247/solr.xml
[junit4:junit4]   2> 15980 T2013 oasc.CoreContainer.<init> New CoreContainer 477044166
[junit4:junit4]   2> 15981 T2013 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361511131247/'
[junit4:junit4]   2> 15981 T2013 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361511131247/'
[junit4:junit4]   2> 15997 T2013 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 15997 T2013 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 15997 T2013 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 15998 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 15998 T2013 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 15998 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 15999 T2013 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 15999 T2013 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 15999 T2013 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 16000 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 16005 T2013 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 16014 T2013 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:50663/solr
[junit4:junit4]   2> 16014 T2013 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 16015 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@2c406ce7
[junit4:junit4]   2> 16016 T2097 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 16016 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 16016 T2097 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 16016 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56641
[junit4:junit4]   2> 16016 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56641
[junit4:junit4]   2> 16017 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b000b with negotiated timeout 20000 for client /127.0.0.1:56641
[junit4:junit4]   2> 16017 T2097 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b000b, negotiated timeout = 20000
[junit4:junit4]   2> 16017 T2098 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2c406ce7 name:ZooKeeperConnection Watcher:127.0.0.1:50663 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16017 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 16019 T2018 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d0062ea4b000b
[junit4:junit4]   2> 16019 T2015 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:56641 which had sessionid 0x13d0062ea4b000b
[junit4:junit4]   2> 16019 T2013 oaz.ZooKeeper.close Session: 0x13d0062ea4b000b closed
[junit4:junit4]   2> 16020 T2098 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 16020 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 16023 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@3d0566b5
[junit4:junit4]   2> 16024 T2099 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 16024 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 16025 T2099 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 16025 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56642
[junit4:junit4]   2> 16025 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56642
[junit4:junit4]   2> 16026 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b000c with negotiated timeout 20000 for client /127.0.0.1:56642
[junit4:junit4]   2> 16026 T2099 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b000c, negotiated timeout = 20000
[junit4:junit4]   2> 16026 T2100 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3d0566b5 name:ZooKeeperConnection Watcher:127.0.0.1:50663/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16026 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 16027 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b000c type:create cxid:0x1 zxid:0xa2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16027 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b000c type:create cxid:0x2 zxid:0xa3 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16028 T2013 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 16904 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16905 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:46416",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:46416_",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 16908 T2054 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> 16908 T2041 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> 16908 T2100 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> 16908 T2084 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> 16908 T2069 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> 16909 T2035 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> 17030 T2013 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36680_
[junit4:junit4]   2> 17031 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b000c type:delete cxid:0xb zxid:0xa8 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:36680_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:36680_
[junit4:junit4]   2> 17031 T2013 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36680_
[junit4:junit4]   2> 17033 T2035 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> 17033 T2054 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> 17034 T2069 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17034 T2100 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17034 T2041 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17034 T2100 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> 17034 T2084 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17034 T2069 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> 17035 T2084 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> 17034 T2041 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> 17035 T2054 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17035 T2035 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17039 T2101 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361511131247/collection1
[junit4:junit4]   2> 17039 T2101 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 17040 T2101 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 17040 T2101 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 17041 T2101 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361511131247/collection1/'
[junit4:junit4]   2> 17041 T2101 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361511131247/collection1/lib/README' to classloader
[junit4:junit4]   2> 17041 T2101 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361511131247/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 17085 T2101 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 17112 T2101 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 17113 T2101 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 17116 T2101 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 17412 T2101 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 17417 T2101 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 17419 T2101 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 17425 T2101 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17427 T2101 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17429 T2101 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17430 T2101 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17431 T2101 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty4-1361511131247/collection1/, dataDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/
[junit4:junit4]   2> 17431 T2101 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@9530a8a
[junit4:junit4]   2> 17431 T2101 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 17432 T2101 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4 forceNew:false
[junit4:junit4]   2> 17432 T2101 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4
[junit4:junit4]   2> 17432 T2101 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index/
[junit4:junit4]   2> 17432 T2101 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 17433 T2101 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index forceNew:false
[junit4:junit4]   2> 17433 T2101 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@38bd7c64 lockFactory=org.apache.lucene.store.NativeFSLockFactory@65a5361b; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 17434 T2101 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 17434 T2101 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index
[junit4:junit4]   2> 17435 T2101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 17435 T2101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 17435 T2101 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 17436 T2101 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 17436 T2101 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 17436 T2101 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 17436 T2101 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 17437 T2101 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 17437 T2101 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 17439 T2101 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 17441 T2101 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4
[junit4:junit4]   2> 17441 T2101 oass.SolrIndexSearcher.<init> Opening Searcher@2e93fe23 main
[junit4:junit4]   2> 17441 T2101 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/tlog
[junit4:junit4]   2> 17442 T2101 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 17442 T2101 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 17444 T2102 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2e93fe23 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 17445 T2101 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 17445 T2101 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 18412 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18412 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:36680",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:36680_",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 18413 T2036 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 18413 T2036 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 18415 T2035 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> 18415 T2069 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> 18415 T2084 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> 18415 T2054 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> 18415 T2100 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> 18415 T2041 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> 18447 T2101 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 18447 T2101 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:36680 collection:collection1 shard:shard1
[junit4:junit4]   2> 18449 T2101 oasc.ZkController.register We are http://127.0.0.1:36680/collection1/ and leader is http://127.0.0.1:33717/collection1/
[junit4:junit4]   2> 18450 T2101 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:36680
[junit4:junit4]   2> 18450 T2101 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 18450 T2101 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C219 name=collection1 org.apache.solr.core.SolrCore@6cd62822 url=http://127.0.0.1:36680/collection1 node=127.0.0.1:36680_ C219_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:36680, roles=null, node_name=127.0.0.1:36680_, collection=collection1, state=down, shard=null, core=collection1}
[junit4:junit4]   2> 18451 T2103 C219 P36680 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 18451 T2101 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18451 T2103 C219 P36680 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 18451 T2103 C219 P36680 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 18451 T2103 C219 P36680 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 18451 T2013 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 18452 T2103 C219 P36680 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18452 T2013 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 18452 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18456 T2013 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18457 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18460 T2048 oasc.CoreContainer.create Creating SolrCore 'unloadcollection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/unloadcollection1
[junit4:junit4]   2> 18461 T2048 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4]   2> 18461 T2048 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:unloadcollection
[junit4:junit4]   2> 18461 T2048 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4]   2> 18462 T2048 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4]   2> 18463 T2048 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection
[junit4:junit4]   2> 18464 T2048 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4]   2> 18465 T2048 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/unloadcollection1/'
[junit4:junit4]   2> 18513 T2048 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 18543 T2048 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 18544 T2048 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 18547 T2048 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 18845 T2048 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 18850 T2048 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 18852 T2048 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 18859 T2048 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 18861 T2048 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 18864 T2048 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 18865 T2048 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 18866 T2048 oasc.SolrCore.<init> [unloadcollection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/unloadcollection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511133794unloadcollection1_1n/
[junit4:junit4]   2> 18866 T2048 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@9530a8a
[junit4:junit4]   2> 18866 T2048 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 18867 T2048 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511133794unloadcollection1_1n forceNew:false
[junit4:junit4]   2> 18867 T2048 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511133794unloadcollection1_1n
[junit4:junit4]   2> 18867 T2048 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511133794unloadcollection1_1n/index/
[junit4:junit4]   2> 18868 T2048 oasc.SolrCore.initIndex WARNING [unloadcollection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511133794unloadcollection1_1n/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 18868 T2048 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511133794unloadcollection1_1n/index forceNew:false
[junit4:junit4]   2> 18869 T2048 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@799c1556 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e30440c; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 18870 T2048 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 18870 T2048 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511133794unloadcollection1_1n/index
[junit4:junit4]   2> 18871 T2048 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 18871 T2048 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 18872 T2048 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 18872 T2048 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 18872 T2048 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 18873 T2048 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 18873 T2048 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 18873 T2048 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 18874 T2048 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 18876 T2048 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 18878 T2048 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511133794unloadcollection1_1n
[junit4:junit4]   2> 18878 T2048 oass.SolrIndexSearcher.<init> Opening Searcher@35e29413 main
[junit4:junit4]   2> 18878 T2048 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511133794unloadcollection1_1n/tlog
[junit4:junit4]   2> 18879 T2048 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 18879 T2048 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 18881 T2048 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511133794unloadcollection1_1n
[junit4:junit4]   2> 18882 T2104 oasc.SolrCore.registerSearcher [unloadcollection1] Registered new searcher Searcher@35e29413 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 18882 T2048 oasc.ZkController.publish publishing core=unloadcollection1 state=down
[junit4:junit4]   2>  C218_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:46416, roles=null, node_name=127.0.0.1:46416_, collection=collection1, state=recovering, shard=shard2, core=collection1}
[junit4:junit4]   2> 18913 T2087 C218 P46416 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:40361/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 18914 T2087 C218 P46416 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:46416 START replicas=[http://127.0.0.1:40361/collection1/] nUpdates=100
[junit4:junit4]   2> 18914 T2087 C218 P46416 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 18914 T2087 C218 P46416 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 18914 T2087 C218 P46416 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 18914 T2087 C218 P46416 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 18914 T2087 C218 P46416 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 18915 T2087 C218 P46416 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:40361/collection1/. core=collection1
[junit4:junit4]   2> 18915 T2087 C218 P46416 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C220 name=collection1 org.apache.solr.core.SolrCore@77a3b365 url=http://127.0.0.1:40361/collection1 node=127.0.0.1:40361_ C220_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:40361, roles=null, node_name=127.0.0.1:40361_, collection=collection1, state=active, shard=shard2, core=collection1, leader=true}
[junit4:junit4]   2> 18919 T2047 C220 P40361 REQ /get {wt=javabin&qt=/get&getVersions=100&distrib=false&version=2} status=0 QTime=0 
[junit4:junit4]   2> 18920 T2050 C220 P40361 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 18921 T2050 C220 P40361 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1
[junit4:junit4]   2> 18922 T2050 C220 P40361 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@243b4189 lockFactory=org.apache.lucene.store.NativeFSLockFactory@29464568; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 18923 T2050 C220 P40361 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 18923 T2050 C220 P40361 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@243b4189 lockFactory=org.apache.lucene.store.NativeFSLockFactory@29464568; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@243b4189 lockFactory=org.apache.lucene.store.NativeFSLockFactory@29464568; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 18924 T2050 C220 P40361 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 18924 T2050 C220 P40361 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1
[junit4:junit4]   2> 18924 T2050 C220 P40361 oass.SolrIndexSearcher.<init> Opening Searcher@382549ba realtime
[junit4:junit4]   2> 18924 T2050 C220 P40361 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 18925 T2050 C220 P40361 /update {wt=javabin&waitSearcher=true&commit=true&version=2&softCommit=false&commit_end_point=true&openSearcher=false} {commit=} 0 5
[junit4:junit4]   2> 18925 T2087 C218 P46416 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 18926 T2087 C218 P46416 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 18927 T2047 C220 P40361 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 18928 T2047 C220 P40361 REQ /replication {qt=/replication&wt=javabin&command=indexversion&version=2} status=0 QTime=2 
[junit4:junit4]   2> 18928 T2087 C218 P46416 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 18929 T2087 C218 P46416 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 18929 T2087 C218 P46416 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 18930 T2050 C220 P40361 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1
[junit4:junit4]   2> 18931 T2050 C220 P40361 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty1/index
[junit4:junit4]   2> 18931 T2050 C220 P40361 REQ /replication {qt=/replication&wt=javabin&command=filelist&version=2&generation=2} status=0 QTime=1 
[junit4:junit4]   2> 18931 T2087 C218 P46416 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 18932 T2087 C218 P46416 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index.20130222133214265 forceNew:false
[junit4:junit4]   2> 18932 T2087 C218 P46416 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3
[junit4:junit4]   2> 18932 T2087 C218 P46416 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@7b661e6a lockFactory=org.apache.lucene.store.NativeFSLockFactory@45b8c54d; maxCacheMB=0.1767578125 maxMergeSizeMB=0.5439453125)) fullCopy=true
[junit4:junit4]   2> 18935 T2049 C220 P40361 REQ /replication {wt=filestream&qt=/replication&command=filecontent&file=segments_2&checksum=true&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 18937 T2087 C218 P46416 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index
[junit4:junit4]   2> 18937 T2087 C218 P46416 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 18937 T2087 C218 P46416 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130222133214265
[junit4:junit4]   2> 18937 T2087 C218 P46416 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3
[junit4:junit4]   2> 18939 T2087 C218 P46416 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3
[junit4:junit4]   2> 18939 T2087 C218 P46416 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index/ new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index.20130222133214265
[junit4:junit4]   2> 18940 T2087 C218 P46416 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@7b661e6a lockFactory=org.apache.lucene.store.NativeFSLockFactory@45b8c54d; maxCacheMB=0.1767578125 maxMergeSizeMB=0.5439453125)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 18940 T2087 C218 P46416 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 18940 T2087 C218 P46416 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3
[junit4:junit4]   2> 18941 T2087 C218 P46416 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 18941 T2087 C218 P46416 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 18941 T2087 C218 P46416 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 18941 T2087 C218 P46416 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index.20130222133214265
[junit4:junit4]   2> 18941 T2087 C218 P46416 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3
[junit4:junit4]   2> 18942 T2087 C218 P46416 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@7b661e6a lockFactory=org.apache.lucene.store.NativeFSLockFactory@45b8c54d; maxCacheMB=0.1767578125 maxMergeSizeMB=0.5439453125)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 18942 T2087 C218 P46416 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 18942 T2087 C218 P46416 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 18942 T2087 C218 P46416 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3
[junit4:junit4]   2> 18943 T2087 C218 P46416 oass.SolrIndexSearcher.<init> Opening Searcher@20714c37 main
[junit4:junit4]   2> 18943 T2086 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@20714c37 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 18944 T2086 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index
[junit4:junit4]   2> 18944 T2087 C218 P46416 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 18944 T2087 C218 P46416 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@7b661e6a lockFactory=org.apache.lucene.store.NativeFSLockFactory@45b8c54d; maxCacheMB=0.1767578125 maxMergeSizeMB=0.5439453125)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@7b661e6a lockFactory=org.apache.lucene.store.NativeFSLockFactory@45b8c54d; maxCacheMB=0.1767578125 maxMergeSizeMB=0.5439453125)),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 18944 T2087 C218 P46416 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 18944 T2087 C218 P46416 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3
[junit4:junit4]   2> 18945 T2087 C218 P46416 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 18945 T2087 C218 P46416 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index.20130222133214265
[junit4:junit4]   2> 18945 T2087 C218 P46416 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty3/index
[junit4:junit4]   2> 18945 T2087 C218 P46416 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 18945 T2087 C218 P46416 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 18945 T2087 C218 P46416 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 18945 T2087 C218 P46416 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 18946 T2087 C218 P46416 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 19918 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 19919 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:36680",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:36680_",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 19921 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:40361",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:40361_",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"unloadcollection1"}
[junit4:junit4]   2> 19921 T2036 oasc.Overseer$ClusterStateUpdater.createCollection Create collection unloadcollection with numShards 1
[junit4:junit4]   2> 19921 T2036 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 19923 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:46416",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:46416_",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 19926 T2041 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> 19926 T2054 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> 19926 T2100 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> 19926 T2035 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> 19926 T2084 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> 19926 T2069 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> 20885 T2048 oasc.CoreContainer.registerCore registering core: unloadcollection1
[junit4:junit4]   2> 20885 T2048 oasc.ZkController.register Register replica - core:unloadcollection1 address:http://127.0.0.1:40361 collection:unloadcollection shard:shard1
[junit4:junit4]   2> 20886 T2048 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection/leader_elect/shard1/election
[junit4:junit4]   2> 20891 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0006 type:delete cxid:0xa6 zxid:0xc3 txntype:-1 reqpath:n/a Error Path:/solr/collections/unloadcollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/unloadcollection/leaders
[junit4:junit4]   2> 20892 T2048 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 20892 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0006 type:create cxid:0xa7 zxid:0xc4 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 20893 T2048 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 20893 T2048 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 20894 T2048 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:40361/unloadcollection1/
[junit4:junit4]   2> 20894 T2048 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 20916 T2048 oasc.SyncStrategy.syncToMe http://127.0.0.1:40361/unloadcollection1/ has no replicas
[junit4:junit4]   2> 20916 T2048 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:40361/unloadcollection1/
[junit4:junit4]   2> 20916 T2048 oascc.SolrZkClient.makePath makePath: /collections/unloadcollection/leaders/shard1
[junit4:junit4]   2> 20919 T2018 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d0062ea4b0006 type:create cxid:0xb1 zxid:0xc8 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21430 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21435 T2035 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> 21435 T2084 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> 21435 T2041 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> 21435 T2100 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> 21435 T2054 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> 21435 T2069 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> 21471 T2048 oasc.ZkController.register We are http://127.0.0.1:40361/unloadcollection1/ and leader is http://127.0.0.1:40361/unloadcollection1/
[junit4:junit4]   2> 21472 T2048 oasc.ZkController.register No LogReplay needed for core=unloadcollection1 baseURL=http://127.0.0.1:40361
[junit4:junit4]   2> 21472 T2048 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 21472 T2048 oasc.ZkController.publish publishing core=unloadcollection1 state=active
[junit4:junit4]   2> 21473 T2048 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21474 T2048 oasc.CoreContainer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/solr.xml
[junit4:junit4]   2> 21480 T2048 oasc.SolrXMLSerializer.persistFile Persisting cores config to /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty1-1361511118674/solr.xml
[junit4:junit4]   2> 21481 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 21482 T2013 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:50663/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@87326b9
[junit4:junit4]   2> 21483 T2013 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 21483 T2107 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:50663. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 21483 T2107 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:50663, initiating session
[junit4:junit4]   2> 21484 T2015 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:56653
[junit4:junit4]   2> 21484 T2015 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:56653
[junit4:junit4]   2> 21484 T2017 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d0062ea4b000d with negotiated timeout 10000 for client /127.0.0.1:56653
[junit4:junit4]   2> 21485 T2107 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:50663, sessionid = 0x13d0062ea4b000d, negotiated timeout = 10000
[junit4:junit4]   2> 21485 T2108 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@87326b9 name:ZooKeeperConnection Watcher:127.0.0.1:50663/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 21485 T2013 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 21486 T2013 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 21488 T2013 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21488 T2013 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 21491 T2064 oasc.CoreContainer.create Creating SolrCore 'unloadcollection2' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171/unloadcollection2
[junit4:junit4]   2> 21492 T2064 oasc.ZkController.createCollectionZkNode Check for collection zkNode:unloadcollection
[junit4:junit4]   2> 21492 T2064 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 21492 T2064 oasc.ZkController.readConfigName Load collection config from:/collections/unloadcollection
[junit4:junit4]   2> 21493 T2064 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171/unloadcollection2/'
[junit4:junit4]   2> 21516 T2064 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 21542 T2064 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 21543 T2064 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 21546 T2064 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 21835 T2064 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 21839 T2064 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 21841 T2064 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 21848 T2064 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 21850 T2064 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 21853 T2064 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 21854 T2064 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 21854 T2064 oasc.SolrCore.<init> [unloadcollection2] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-jetty2-1361511123171/unloadcollection2/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511136825unloadcollection1_2n/
[junit4:junit4]   2> 21855 T2064 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@9530a8a
[junit4:junit4]   2> 21855 T2064 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 21856 T2064 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511136825unloadcollection1_2n forceNew:false
[junit4:junit4]   2> 21856 T2064 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511136825unloadcollection1_2n
[junit4:junit4]   2> 21856 T2064 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511136825unloadcollection1_2n/index/
[junit4:junit4]   2> 21856 T2064 oasc.SolrCore.initIndex WARNING [unloadcollection2] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511136825unloadcollection1_2n/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 21857 T2064 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511136825unloadcollection1_2n/index forceNew:false
[junit4:junit4]   2> 21858 T2064 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@9b62a7e lockFactory=org.apache.lucene.store.NativeFSLockFactory@3d6b2871; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 21858 T2064 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 21859 T2064 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511136825unloadcollection1_2n/index
[junit4:junit4]   2> 21860 T2064 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 21860 T2064 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 21860 T2064 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 21861 T2064 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 21861 T2064 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 21861 T2064 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 21862 T2064 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 21862 T2064 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 21862 T2064 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 21864 T2064 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 21866 T2064 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511136825unloadcollection1_2n
[junit4:junit4]   2> 21867 T2064 oass.SolrIndexSearcher.<init> Opening Searcher@354261a0 main
[junit4:junit4]   2> 21867 T2064 oasu.UpdateHandler.clearLog Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511136825unloadcollection1_2n/tlog
[junit4:junit4]   2> 21868 T2064 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 21868 T2064 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 21870 T2064 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-UnloadDistributedZkTest-1361511115336/1361511136825unloadcollection1_2n
[junit4:junit4]   2> 21871 T2109 oasc.SolrCore.registerSearcher [unloadcollection2] Registered new searcher Searcher@354261a0 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 21871 T2064 oasc.ZkController.publish publishing core=unloadcollection2 state=down
[junit4:junit4]   2> 21872 T2064 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2>  C219_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:36680, roles=null, node_name=127.0.0.1:36680_, collection=collection1, state=recovering, shard=shard1, core=collection1}
[junit4:junit4]   2> 22456 T2103 C219 P36680 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:33717/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 22456 T2103 C219 P36680 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:36680 START replicas=[http://127.0.0.1:33717/collection1/] nUpdates=100
[junit4:junit4]   2> 22457 T2103 C219 P36680 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 22457 T2103 C219 P36680 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 22457 T2103 C219 P36680 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 22457 T2103 C219 P36680 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 22457 T2103 C219 P36680 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 22457 T2103 C219 P36680 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:33717/collection1/. core=collection1
[junit4:junit4]   2> 22457 T2103 C219 P36680 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C221 name=collection1 org.apache.solr.core.SolrCore@57a108f2 url=http://127.0.0.1:33717/collection1 node=127.0.0.1:33717_ C221_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:33717, roles=null, node_name=127.0.0.1:33717_, collection=collection1, state=active, shard=shard1, core=collection1, leader=true}
[junit4:junit4]   2> 22462 T2062 C221 P33717 REQ /get {wt=javabin&qt=/get&getVersions=100&distrib=false&version=2} status=0 QTime=2 
[junit4:junit4]   2> 22462 T2063 C221 P33717 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 22463 T2063 C221 P33717 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2
[junit4:junit4]   2> 22465 T2063 C221 P33717 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@506736b lockFactory=org.apache.lucene.store.NativeFSLockFactory@7b6cfb73; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 22465 T2063 C221 P33717 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 22466 T2063 C221 P33717 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@506736b lockFactory=org.apache.lucene.store.NativeFSLockFactory@7b6cfb73; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@506736b lockFactory=org.apache.lucene.store.NativeFSLockFactory@7b6cfb73; maxCacheMB=0.9521484375 maxMergeSizeMB=0.453125))),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 22466 T2063 C221 P33717 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 22467 T2063 C221 P33717 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2
[junit4:junit4]   2> 22467 T2063 C221 P33717 oass.SolrIndexSearcher.<init> Opening Searcher@1e843917 realtime
[junit4:junit4]   2> 22467 T2063 C221 P33717 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 22468 T2063 C221 P33717 /update {wt=javabin&waitSearcher=true&commit=true&version=2&softCommit=false&commit_end_point=true&openSearcher=false} {commit=} 0 6
[junit4:junit4]   2> 22469 T2103 C219 P36680 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 22469 T2103 C219 P36680 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 22470 T2063 C221 P33717 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 22471 T2063 C221 P33717 REQ /replication {qt=/replication&wt=javabin&command=indexversion&version=2} status=0 QTime=1 
[junit4:junit4]   2> 22471 T2103 C219 P36680 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 22471 T2103 C219 P36680 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 22471 T2103 C219 P36680 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 22473 T2065 C221 P33717 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2
[junit4:junit4]   2> 22473 T2065 C221 P33717 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty2/index
[junit4:junit4]   2> 22473 T2065 C221 P33717 REQ /replication {qt=/replication&wt=javabin&command=filelist&version=2&generation=2} status=0 QTime=0 
[junit4:junit4]   2> 22474 T2103 C219 P36680 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 22474 T2103 C219 P36680 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index.20130222133217808 forceNew:false
[junit4:junit4]   2> 22475 T2103 C219 P36680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4
[junit4:junit4]   2> 22475 T2103 C219 P36680 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1eeb8f6e lockFactory=org.apache.lucene.store.NativeFSLockFactory@532913; maxCacheMB=0.1767578125 maxMergeSizeMB=0.5439453125)) fullCopy=true
[junit4:junit4]   2> 22478 T2062 C221 P33717 REQ /replication {wt=filestream&qt=/replication&command=filecontent&file=segments_2&checksum=true&generation=2} status=0 QTime=1 
[junit4:junit4]   2> 22479 T2103 C219 P36680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index
[junit4:junit4]   2> 22480 T2103 C219 P36680 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 22480 T2103 C219 P36680 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130222133217808
[junit4:junit4]   2> 22480 T2103 C219 P36680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4
[junit4:junit4]   2> 22480 T2103 C219 P36680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4
[junit4:junit4]   2> 22480 T2103 C219 P36680 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index/ new=./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index.20130222133217808
[junit4:junit4]   2> 22481 T2103 C219 P36680 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1eeb8f6e lockFactory=org.apache.lucene.store.NativeFSLockFactory@532913; maxCacheMB=0.1767578125 maxMergeSizeMB=0.5439453125)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 22482 T2103 C219 P36680 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 22482 T2103 C219 P36680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4
[junit4:junit4]   2> 22482 T2103 C219 P36680 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 22482 T2103 C219 P36680 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 22482 T2103 C219 P36680 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 22483 T2103 C219 P36680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index.20130222133217808
[junit4:junit4]   2> 22483 T2103 C219 P36680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4
[junit4:junit4]   2> 22483 T2103 C219 P36680 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1eeb8f6e lockFactory=org.apache.lucene.store.NativeFSLockFactory@532913; maxCacheMB=0.1767578125 maxMergeSizeMB=0.5439453125)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 22483 T2103 C219 P36680 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 22484 T2103 C219 P36680 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 22484 T2103 C219 P36680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4
[junit4:junit4]   2> 22484 T2103 C219 P36680 oass.SolrIndexSearcher.<init> Opening Searcher@3932bed8 main
[junit4:junit4]   2> 22484 T2102 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3932bed8 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 22485 T2102 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index
[junit4:junit4]   2> 22485 T2103 C219 P36680 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=false,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 22485 T2103 C219 P36680 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1eeb8f6e lockFactory=org.apache.lucene.store.NativeFSLockFactory@532913; maxCacheMB=0.1767578125 maxMergeSizeMB=0.5439453125)),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@1eeb8f6e lockFactory=org.apache.lucene.store.NativeFSLockFactory@532913; maxCacheMB=0.1767578125 maxMergeSizeMB=0.5439453125)),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 22485 T2103 C219 P36680 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 22485 T2103 C219 P36680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4
[junit4:junit4]   2> 22486 T2103 C219 P36680 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 22486 T2103 C219 P36680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index.20130222133217808
[junit4:junit4]   2> 22486 T2103 C219 P36680 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.UnloadDistributedZkTest-1361511115336/jetty4/index
[junit4:junit4]   2> 22486 T2103 C219 P36680 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 22486 T2103 C219 P36680 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 22486 T2103 C219 P36680 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 22486 T2103 C219 P36680 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 22487 T2103 C219 P36680 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 22939 T2036 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22939 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:40361",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:40361_",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"unloadcollection1"}
[junit4:junit4]   2> 22941 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:33717",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:33717_",
[junit4:junit4]   2> 	  "collection":"unloadcollection",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"unloadcollection2"}
[junit4:junit4]   2> 22942 T2036 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 22942 T2036 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 22944 T2036 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:36680",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:36680_",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 22947 T2069 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> 22947 T2035 oascc.ZkStateReader$2.process A cluster stat

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

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> 150908 T2013 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 150916 T2012 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 3 leaked thread(s).
[junit4:junit4]   2> 151666 T2016 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> 151975 T2100 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 151976 T2100 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> 151976 T2100 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 151976 T2100 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> NOTE: test params are: codec=Appending, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=vi, timezone=Asia/Chongqing
[junit4:junit4]   2> NOTE: Linux 3.2.0-38-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=2,free=119003888,total=486539264
[junit4:junit4]   2> NOTE: All tests run in this JVM: [CacheHeaderTest, JSONWriterTest, TestFiltering, TestIndexingPerformance, TestSearchPerf, DistanceFunctionTest, TestAtomicUpdateErrorCases, DistributedSpellCheckComponentTest, BinaryUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, NumericFieldsTest, TestPerFieldSimilarity, TestWordDelimiterFilterFactory, PreAnalyzedFieldTest, FileBasedSpellCheckerTest, TestSuggestSpellingConverter, TestCollationKeyRangeQueries, DistributedTermsComponentTest, SOLR749Test, TestPartialUpdateDeduplication, SpellCheckComponentTest, ChaosMonkeyNothingIsSafeTest, TestQuerySenderNoQuery, TestIndexSearcher, SolrCoreCheckLockOnStartupTest, TestReload, CopyFieldTest, AutoCommitTest, AnalysisAfterCoreReloadTest, PathHierarchyTokenizerFactoryTest, TestStressRecovery, SolrRequestParserTest, MBeansHandlerTest, TestConfig, TestStressVersions, SimpleFacetsTest, ClusterStateTest, ClusterStateUpdateTest, TestDocumentBuilder, TestQueryTypes, TestFastWriter, SampleTest, SolrPluginUtilsTest, MinimalSchemaTest, TestCollationField, CollectionsAPIDistributedZkTest, TestComponentsName, DirectUpdateHandlerOptimizeTest, SortByFunctionTest, TestSolr4Spatial, TestPseudoReturnFields, StatelessScriptUpdateProcessorFactoryTest, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, CoreAdminHandlerTest, TestIBSimilarityFactory, ZkControllerTest, BadComponentTest, CSVRequestHandlerTest, SuggesterWFSTTest, BasicDistributedZkTest, LeaderElectionIntegrationTest, SpellCheckCollatorTest, TestExtendedDismaxParser, TestFaceting, WordBreakSolrSpellCheckerTest, TestJoin, DirectSolrSpellCheckerTest, ConvertedLegacyTest, QueryElevationComponentTest, BasicFunctionalityTest, TestBinaryResponseWriter, TestJmxIntegration, TestTrie, DirectSolrConnectionTest, TestRemoteStreaming, QueryEqualityTest, TestCSVLoader, PolyFieldTest, TestCSVResponseWriter, CurrencyFieldTest, SolrInfoMBeanTest, TermVectorComponentTest, TestSurroundQueryParser, TestSolrQueryParser, FastVectorHighlighterTest, RequestHandlersTest, RequiredFieldsTest, LoggingHandlerTest, SuggesterTSTTest, UniqFieldsUpdateProcessorFactoryTest, UpdateParamsTest, SuggesterTest, NotRequiredUniqueKeyTest, TestFoldingMultitermQuery, TestLuceneMatchVersion, TestWriterPerf, PrimitiveFieldTypeTest, TestUpdate, HighlighterConfigTest, TestXIncludeConfig, TestQuerySenderListener, MultiTermTest, EchoParamsTest, SpellPossibilityIteratorTest, TestPhraseSuggestions, TestLMJelinekMercerSimilarityFactory, TestDefaultSimilarityFactory, TestFastLRUCache, URLClassifyProcessorTest, OpenExchangeRatesOrgProviderTest, FileUtilsTest, DateFieldTest, TestBadConfig, SystemInfoHandlerTest, TestUtils, CircularListTest, TestCharFilters, BasicDistributedZk2Test, SliceStateUpdateTest, TestZkChroot, UnloadDistributedZkTest]
[junit4:junit4] Completed on J0 in 152.19s, 1 test, 1 failure <<< FAILURES!

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

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



Mime
View raw message