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 # 4498 - Still Failing!
Date Fri, 01 Mar 2013 01:15:27 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/4498/
Java: 64bit/jdk1.8.0-ea-b65 -XX:+UseG1GC

1 tests failed.
FAILED:  org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch

Error Message:
expected:<1> but was:<0>

Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
	at __randomizedtesting.SeedInfo.seed([CD5185DB371DF91:8D339645C42EBFAD]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:205)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:85)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
	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 9496 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4:junit4]   2> 0 T1908 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4]   2> 3 T1908 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-BasicDistributedZk2Test-1362100290437
[junit4:junit4]   2> 4 T1908 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 4 T1909 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 5 T1909 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 5 T1909 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 5 T1909 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 5 T1909 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 6 T1909 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.BasicDistributedZk2Test-1362100290437/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   2> 104 T1908 oasc.ZkTestServer.run start zk server on port:40687
[junit4:junit4]   2> 105 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7a5ab3f9
[junit4:junit4]   2> 106 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 106 T1914 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 106 T1914 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 106 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44131
[junit4:junit4]   2> 107 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44131
[junit4:junit4]   2> 107 T1912 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 148 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d238103890000 with negotiated timeout 10000 for client /127.0.0.1:44131
[junit4:junit4]   2> 148 T1914 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d238103890000, negotiated timeout = 10000
[junit4:junit4]   2> 149 T1915 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a5ab3f9 name:ZooKeeperConnection Watcher:127.0.0.1:40687 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 150 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 150 T1908 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 155 T1913 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d238103890000
[junit4:junit4]   2> 155 T1915 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 156 T1910 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:44131 which had sessionid 0x13d238103890000
[junit4:junit4]   2> 155 T1908 oaz.ZooKeeper.close Session: 0x13d238103890000 closed
[junit4:junit4]   2> 157 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@51c54d4d
[junit4:junit4]   2> 158 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 158 T1916 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 158 T1916 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 158 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44132
[junit4:junit4]   2> 159 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44132
[junit4:junit4]   2> 159 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d238103890001 with negotiated timeout 10000 for client /127.0.0.1:44132
[junit4:junit4]   2> 160 T1916 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d238103890001, negotiated timeout = 10000
[junit4:junit4]   2> 160 T1917 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@51c54d4d name:ZooKeeperConnection Watcher:127.0.0.1:40687/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 160 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 160 T1908 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 163 T1908 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 164 T1908 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 165 T1908 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 167 T1908 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> 168 T1908 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 170 T1908 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> 171 T1908 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 174 T1908 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> 175 T1908 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 179 T1908 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> 180 T1908 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 181 T1908 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> 181 T1908 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 183 T1908 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> 184 T1908 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 185 T1908 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> 186 T1908 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 187 T1908 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> 188 T1908 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 190 T1908 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> 190 T1908 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 192 T1913 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d238103890001
[junit4:junit4]   2> 192 T1917 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 192 T1910 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:44132 which had sessionid 0x13d238103890001
[junit4:junit4]   2> 192 T1908 oaz.ZooKeeper.close Session: 0x13d238103890001 closed
[junit4:junit4]   2> 245 T1908 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 377 T1908 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:48961
[junit4:junit4]   2> 377 T1908 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 378 T1908 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 378 T1908 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.BasicDistributedZk2Test-controljetty-1362100290627
[junit4:junit4]   2> 379 T1908 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.BasicDistributedZk2Test-controljetty-1362100290627/solr.xml
[junit4:junit4]   2> 379 T1908 oasc.CoreContainer.<init> New CoreContainer 1507122032
[junit4:junit4]   2> 379 T1908 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.BasicDistributedZk2Test-controljetty-1362100290627/'
[junit4:junit4]   2> 380 T1908 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.BasicDistributedZk2Test-controljetty-1362100290627/'
[junit4:junit4]   2> 401 T1908 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 401 T1908 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 402 T1908 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 402 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 403 T1908 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 403 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 403 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 404 T1908 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 404 T1908 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 408 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 417 T1908 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 428 T1908 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:40687/solr
[junit4:junit4]   2> 428 T1908 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 429 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@6a8c1a10
[junit4:junit4]   2> 430 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 430 T1927 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 430 T1927 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 430 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44133
[junit4:junit4]   2> 431 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44133
[junit4:junit4]   2> 431 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d238103890002 with negotiated timeout 20000 for client /127.0.0.1:44133
[junit4:junit4]   2> 431 T1927 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d238103890002, negotiated timeout = 20000
[junit4:junit4]   2> 432 T1928 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a8c1a10 name:ZooKeeperConnection Watcher:127.0.0.1:40687 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 432 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 433 T1913 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d238103890002
[junit4:junit4]   2> 433 T1928 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 433 T1910 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:44133 which had sessionid 0x13d238103890002
[junit4:junit4]   2> 433 T1908 oaz.ZooKeeper.close Session: 0x13d238103890002 closed
[junit4:junit4]   2> 434 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 437 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@2c69b02e
[junit4:junit4]   2> 438 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 438 T1929 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 438 T1929 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 438 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44134
[junit4:junit4]   2> 439 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44134
[junit4:junit4]   2> 439 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d238103890003 with negotiated timeout 20000 for client /127.0.0.1:44134
[junit4:junit4]   2> 439 T1929 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d238103890003, negotiated timeout = 20000
[junit4:junit4]   2> 440 T1930 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2c69b02e name:ZooKeeperConnection Watcher:127.0.0.1:40687/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 440 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 442 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 443 T1908 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 444 T1908 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48961_
[junit4:junit4]   2> 445 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 type:delete cxid:0x8 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:48961_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:48961_
[junit4:junit4]   2> 445 T1908 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48961_
[junit4:junit4]   2> 447 T1908 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 450 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 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> 450 T1908 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 451 T1908 oasc.Overseer.start Overseer (id=89266604634341379-127.0.0.1:48961_-n_0000000000) starting
[junit4:junit4]   2> 452 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 type:create cxid:0x1b zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 452 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 type:create cxid:0x1c zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 453 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 type:create cxid:0x1d zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 454 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 type:create cxid:0x1e zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 454 T1932 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 454 T1908 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 456 T1908 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 457 T1931 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 460 T1933 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.BasicDistributedZk2Test-controljetty-1362100290627/collection1
[junit4:junit4]   2> 460 T1933 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 461 T1933 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 461 T1933 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 462 T1933 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.BasicDistributedZk2Test-controljetty-1362100290627/collection1/'
[junit4:junit4]   2> 462 T1933 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362100290627/collection1/lib/README' to classloader
[junit4:junit4]   2> 462 T1933 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1362100290627/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 488 T1933 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 519 T1933 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 519 T1933 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 523 T1933 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 896 T1933 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 901 T1933 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 904 T1933 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 913 T1933 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 916 T1933 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 919 T1933 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 920 T1933 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 921 T1933 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.BasicDistributedZk2Test-controljetty-1362100290627/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/control/data/
[junit4:junit4]   2> 921 T1933 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c7fa1a
[junit4:junit4]   2> 921 T1933 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 929 T1933 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.BasicDistributedZk2Test-1362100290437/control/data forceNew:false
[junit4:junit4]   2> 930 T1933 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/control/data
[junit4:junit4]   2> 930 T1933 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/control/data/index/
[junit4:junit4]   2> 931 T1933 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 931 T1933 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.BasicDistributedZk2Test-1362100290437/control/data/index forceNew:false
[junit4:junit4]   2> 934 T1933 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@70941dcd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 935 T1933 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 935 T1933 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/control/data/index
[junit4:junit4]   2> 937 T1933 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 937 T1933 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 937 T1933 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 938 T1933 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 938 T1933 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 938 T1933 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 939 T1933 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 939 T1933 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 939 T1933 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 942 T1933 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 944 T1933 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/control/data
[junit4:junit4]   2> 945 T1933 oass.SolrIndexSearcher.<init> Opening Searcher@62a10d45 main
[junit4:junit4]   2> 945 T1933 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 946 T1933 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 948 T1933 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/control/data
[junit4:junit4]   2> 949 T1934 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@62a10d45 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 949 T1933 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 950 T1933 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 950 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 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> 1960 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1960 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core_node_name":"1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48961",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48961_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 1960 T1931 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 1960 T1931 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 1961 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 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> 1964 T1930 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> 2954 T1933 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 2954 T1933 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:48961 collection:control_collection shard:shard1
[junit4:junit4]   2> 2955 T1933 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 2960 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 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> 2960 T1933 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2961 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2962 T1933 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2962 T1933 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2962 T1933 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:48961/collection1/
[junit4:junit4]   2> 2963 T1933 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2963 T1933 oasc.SyncStrategy.syncToMe http://127.0.0.1:48961/collection1/ has no replicas
[junit4:junit4]   2> 2963 T1933 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:48961/collection1/
[junit4:junit4]   2> 2963 T1933 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 2966 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3467 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3474 T1930 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> 3519 T1933 oasc.ZkController.register We are http://127.0.0.1:48961/collection1/ and leader is http://127.0.0.1:48961/collection1/
[junit4:junit4]   2> 3520 T1933 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48961
[junit4:junit4]   2> 3520 T1933 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3520 T1933 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 3520 T1933 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3522 T1933 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3523 T1908 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3523 T1908 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3524 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3528 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 3530 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@79083207
[junit4:junit4]   2> 3531 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3532 T1935 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3533 T1935 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 3533 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44135
[junit4:junit4]   2> 3533 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44135
[junit4:junit4]   2> 3535 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d238103890004 with negotiated timeout 10000 for client /127.0.0.1:44135
[junit4:junit4]   2> 3535 T1935 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d238103890004, negotiated timeout = 10000
[junit4:junit4]   2> 3536 T1936 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@79083207 name:ZooKeeperConnection Watcher:127.0.0.1:40687/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3536 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3538 T1908 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3539 T1908 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 3602 T1908 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 3607 T1908 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:42736
[junit4:junit4]   2> 3608 T1908 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3608 T1908 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3609 T1908 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.BasicDistributedZk2Test-jetty1-1362100293974
[junit4:junit4]   2> 3609 T1908 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.BasicDistributedZk2Test-jetty1-1362100293974/solr.xml
[junit4:junit4]   2> 3610 T1908 oasc.CoreContainer.<init> New CoreContainer 1116881919
[junit4:junit4]   2> 3611 T1908 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.BasicDistributedZk2Test-jetty1-1362100293974/'
[junit4:junit4]   2> 3611 T1908 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.BasicDistributedZk2Test-jetty1-1362100293974/'
[junit4:junit4]   2> 3630 T1908 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 3631 T1908 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3631 T1908 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 3631 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3632 T1908 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3632 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3632 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3633 T1908 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3633 T1908 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3633 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 3639 T1908 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3649 T1908 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:40687/solr
[junit4:junit4]   2> 3650 T1908 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 3650 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@7e8174bb
[junit4:junit4]   2> 3651 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3651 T1946 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3652 T1946 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 3652 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44136
[junit4:junit4]   2> 3652 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44136
[junit4:junit4]   2> 3653 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d238103890005 with negotiated timeout 20000 for client /127.0.0.1:44136
[junit4:junit4]   2> 3653 T1946 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d238103890005, negotiated timeout = 20000
[junit4:junit4]   2> 3653 T1947 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7e8174bb name:ZooKeeperConnection Watcher:127.0.0.1:40687 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3653 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3655 T1913 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d238103890005
[junit4:junit4]   2> 3655 T1947 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 3655 T1910 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:44136 which had sessionid 0x13d238103890005
[junit4:junit4]   2> 3655 T1908 oaz.ZooKeeper.close Session: 0x13d238103890005 closed
[junit4:junit4]   2> 3656 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 3660 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c68a847
[junit4:junit4]   2> 3661 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3661 T1948 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 3661 T1948 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 3662 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44137
[junit4:junit4]   2> 3662 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44137
[junit4:junit4]   2> 3663 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d238103890006 with negotiated timeout 20000 for client /127.0.0.1:44137
[junit4:junit4]   2> 3663 T1948 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d238103890006, negotiated timeout = 20000
[junit4:junit4]   2> 3663 T1949 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c68a847 name:ZooKeeperConnection Watcher:127.0.0.1:40687/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3663 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3664 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3664 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3665 T1908 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4667 T1908 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42736_
[junit4:junit4]   2> 4668 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890006 type:delete cxid:0x9 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:42736_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:42736_
[junit4:junit4]   2> 4668 T1908 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:42736_
[junit4:junit4]   2> 4670 T1930 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> 4670 T1949 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4670 T1936 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4671 T1930 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4674 T1950 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.BasicDistributedZk2Test-jetty1-1362100293974/collection1
[junit4:junit4]   2> 4675 T1950 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4675 T1950 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4675 T1950 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4676 T1950 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.BasicDistributedZk2Test-jetty1-1362100293974/collection1/'
[junit4:junit4]   2> 4676 T1950 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362100293974/collection1/lib/README' to classloader
[junit4:junit4]   2> 4677 T1950 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362100293974/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4702 T1950 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 4734 T1950 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4735 T1950 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4738 T1950 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4977 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4977 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core_node_name":"1",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48961",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48961_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 4980 T1949 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> 4980 T1936 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> 4980 T1930 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> 5062 T1950 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5066 T1950 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5069 T1950 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5076 T1950 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5079 T1950 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5083 T1950 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5084 T1950 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5085 T1950 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.BasicDistributedZk2Test-jetty1-1362100293974/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/
[junit4:junit4]   2> 5086 T1950 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c7fa1a
[junit4:junit4]   2> 5087 T1950 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 5087 T1950 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.BasicDistributedZk2Test-1362100290437/jetty1 forceNew:false
[junit4:junit4]   2> 5088 T1950 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1
[junit4:junit4]   2> 5088 T1950 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index/
[junit4:junit4]   2> 5088 T1950 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5088 T1950 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.BasicDistributedZk2Test-1362100290437/jetty1/index forceNew:false
[junit4:junit4]   2> 5092 T1950 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2da9f82f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5093 T1950 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5093 T1950 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index
[junit4:junit4]   2> 5095 T1950 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5095 T1950 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5096 T1950 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5096 T1950 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5097 T1950 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5097 T1950 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5097 T1950 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5097 T1950 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5098 T1950 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5100 T1950 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5102 T1950 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1
[junit4:junit4]   2> 5103 T1950 oass.SolrIndexSearcher.<init> Opening Searcher@28c5741a main
[junit4:junit4]   2> 5103 T1950 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5104 T1950 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5106 T1951 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@28c5741a main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5108 T1950 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 5108 T1950 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6483 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6484 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":"2",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42736",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42736_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 6485 T1931 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 2
[junit4:junit4]   2> 6485 T1931 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 6489 T1936 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> 6489 T1949 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> 6489 T1930 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> 7109 T1950 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 7109 T1950 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:42736 collection:collection1 shard:shard2
[junit4:junit4]   2> 7111 T1950 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 7117 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890006 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> 7118 T1950 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7119 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890006 type:create cxid:0x47 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7121 T1950 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7121 T1950 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7121 T1950 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:42736/collection1/
[junit4:junit4]   2> 7122 T1950 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 7122 T1950 oasc.SyncStrategy.syncToMe http://127.0.0.1:42736/collection1/ has no replicas
[junit4:junit4]   2> 7123 T1950 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:42736/collection1/
[junit4:junit4]   2> 7123 T1950 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 7127 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890006 type:create cxid:0x51 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7993 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8001 T1930 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> 8001 T1949 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> 8001 T1936 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> 8031 T1950 oasc.ZkController.register We are http://127.0.0.1:42736/collection1/ and leader is http://127.0.0.1:42736/collection1/
[junit4:junit4]   2> 8031 T1950 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42736
[junit4:junit4]   2> 8031 T1950 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 8032 T1950 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 8032 T1950 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8035 T1950 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8036 T1908 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 8037 T1908 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8038 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8104 T1908 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 8106 T1908 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:47300
[junit4:junit4]   2> 8106 T1908 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8107 T1908 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8107 T1908 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.BasicDistributedZk2Test-jetty2-1362100298478
[junit4:junit4]   2> 8107 T1908 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.BasicDistributedZk2Test-jetty2-1362100298478/solr.xml
[junit4:junit4]   2> 8108 T1908 oasc.CoreContainer.<init> New CoreContainer 541377984
[junit4:junit4]   2> 8108 T1908 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.BasicDistributedZk2Test-jetty2-1362100298478/'
[junit4:junit4]   2> 8109 T1908 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.BasicDistributedZk2Test-jetty2-1362100298478/'
[junit4:junit4]   2> 8127 T1908 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 8127 T1908 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8128 T1908 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 8128 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8128 T1908 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8129 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8129 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8130 T1908 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8130 T1908 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8130 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 8136 T1908 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8166 T1908 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:40687/solr
[junit4:junit4]   2> 8167 T1908 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 8167 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@35c8887e
[junit4:junit4]   2> 8168 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8168 T1961 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8169 T1961 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 8169 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44140
[junit4:junit4]   2> 8169 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44140
[junit4:junit4]   2> 8170 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d238103890007 with negotiated timeout 20000 for client /127.0.0.1:44140
[junit4:junit4]   2> 8170 T1961 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d238103890007, negotiated timeout = 20000
[junit4:junit4]   2> 8170 T1962 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@35c8887e name:ZooKeeperConnection Watcher:127.0.0.1:40687 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8170 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8172 T1913 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d238103890007
[junit4:junit4]   2> 8172 T1962 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 8172 T1910 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:44140 which had sessionid 0x13d238103890007
[junit4:junit4]   2> 8172 T1908 oaz.ZooKeeper.close Session: 0x13d238103890007 closed
[junit4:junit4]   2> 8173 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 8175 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@16d04b17
[junit4:junit4]   2> 8176 T1963 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 8176 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8177 T1963 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 8177 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44141
[junit4:junit4]   2> 8177 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44141
[junit4:junit4]   2> 8178 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d238103890008 with negotiated timeout 20000 for client /127.0.0.1:44141
[junit4:junit4]   2> 8178 T1963 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d238103890008, negotiated timeout = 20000
[junit4:junit4]   2> 8178 T1964 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16d04b17 name:ZooKeeperConnection Watcher:127.0.0.1:40687/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8178 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8179 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8180 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8181 T1908 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 9182 T1908 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47300_
[junit4:junit4]   2> 9183 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890008 type:delete cxid:0x9 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:47300_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:47300_
[junit4:junit4]   2> 9183 T1908 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:47300_
[junit4:junit4]   2> 9185 T1936 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> 9185 T1949 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9185 T1964 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9185 T1949 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> 9185 T1930 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9186 T1930 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> 9187 T1936 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9191 T1965 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.BasicDistributedZk2Test-jetty2-1362100298478/collection1
[junit4:junit4]   2> 9191 T1965 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 9191 T1965 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 9191 T1965 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 9192 T1965 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.BasicDistributedZk2Test-jetty2-1362100298478/collection1/'
[junit4:junit4]   2> 9193 T1965 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362100298478/collection1/lib/README' to classloader
[junit4:junit4]   2> 9193 T1965 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1362100298478/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 9218 T1965 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 9248 T1965 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9249 T1965 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9253 T1965 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9505 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9506 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":"2",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42736",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42736_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 9508 T1964 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> 9508 T1936 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> 9508 T1949 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> 9508 T1930 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> 9575 T1965 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9580 T1965 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9582 T1965 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9595 T1965 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9599 T1965 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9604 T1965 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9605 T1965 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9605 T1965 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.BasicDistributedZk2Test-jetty2-1362100298478/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/
[junit4:junit4]   2> 9606 T1965 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c7fa1a
[junit4:junit4]   2> 9606 T1965 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 9607 T1965 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.BasicDistributedZk2Test-1362100290437/jetty2 forceNew:false
[junit4:junit4]   2> 9607 T1965 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2
[junit4:junit4]   2> 9607 T1965 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/index/
[junit4:junit4]   2> 9608 T1965 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 9608 T1965 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.BasicDistributedZk2Test-1362100290437/jetty2/index forceNew:false
[junit4:junit4]   2> 9612 T1965 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@45b764fb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9612 T1965 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 9613 T1965 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/index
[junit4:junit4]   2> 9614 T1965 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 9615 T1965 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 9615 T1965 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 9616 T1965 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9616 T1965 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9616 T1965 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 9617 T1965 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 9617 T1965 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9617 T1965 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9621 T1965 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9624 T1965 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2
[junit4:junit4]   2> 9625 T1965 oass.SolrIndexSearcher.<init> Opening Searcher@7796e43d main
[junit4:junit4]   2> 9626 T1965 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9626 T1965 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9629 T1966 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7796e43d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9630 T1965 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 9631 T1965 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11011 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11012 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":"3",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47300",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47300_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 11013 T1931 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 11013 T1931 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 11017 T1949 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> 11017 T1936 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> 11017 T1930 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> 11017 T1964 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> 11632 T1965 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 11632 T1965 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:47300 collection:collection1 shard:shard1
[junit4:junit4]   2> 11634 T1965 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 11639 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890008 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> 11640 T1965 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 11640 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890008 type:create cxid:0x46 zxid:0x7f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 11641 T1965 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 11641 T1965 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 11642 T1965 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:47300/collection1/
[junit4:junit4]   2> 11642 T1965 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 11642 T1965 oasc.SyncStrategy.syncToMe http://127.0.0.1:47300/collection1/ has no replicas
[junit4:junit4]   2> 11642 T1965 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:47300/collection1/
[junit4:junit4]   2> 11642 T1965 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 11645 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890008 type:create cxid:0x4f zxid:0x82 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12521 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12526 T1936 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> 12526 T1964 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> 12526 T1949 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> 12526 T1930 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> 12548 T1965 oasc.ZkController.register We are http://127.0.0.1:47300/collection1/ and leader is http://127.0.0.1:47300/collection1/
[junit4:junit4]   2> 12548 T1965 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:47300
[junit4:junit4]   2> 12548 T1965 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 12548 T1965 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 12549 T1965 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12551 T1965 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12552 T1908 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 12552 T1908 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 12553 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12621 T1908 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 12624 T1908 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:55295
[junit4:junit4]   2> 12625 T1908 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 12626 T1908 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 12626 T1908 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.BasicDistributedZk2Test-jetty3-1362100302991
[junit4:junit4]   2> 12627 T1908 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.BasicDistributedZk2Test-jetty3-1362100302991/solr.xml
[junit4:junit4]   2> 12627 T1908 oasc.CoreContainer.<init> New CoreContainer 1943849615
[junit4:junit4]   2> 12628 T1908 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.BasicDistributedZk2Test-jetty3-1362100302991/'
[junit4:junit4]   2> 12628 T1908 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.BasicDistributedZk2Test-jetty3-1362100302991/'
[junit4:junit4]   2> 12648 T1908 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 12648 T1908 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 12648 T1908 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 12649 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 12649 T1908 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 12650 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 12650 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 12650 T1908 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 12651 T1908 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 12651 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 12658 T1908 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 12668 T1908 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:40687/solr
[junit4:junit4]   2> 12669 T1908 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 12669 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@26c01813
[junit4:junit4]   2> 12670 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12671 T1976 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12671 T1976 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 12671 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44143
[junit4:junit4]   2> 12671 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44143
[junit4:junit4]   2> 12672 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d238103890009 with negotiated timeout 20000 for client /127.0.0.1:44143
[junit4:junit4]   2> 12672 T1976 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d238103890009, negotiated timeout = 20000
[junit4:junit4]   2> 12673 T1977 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@26c01813 name:ZooKeeperConnection Watcher:127.0.0.1:40687 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12673 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12674 T1913 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d238103890009
[junit4:junit4]   2> 12675 T1910 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:44143 which had sessionid 0x13d238103890009
[junit4:junit4]   2> 12675 T1977 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 12676 T1908 oaz.ZooKeeper.close Session: 0x13d238103890009 closed
[junit4:junit4]   2> 12677 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 12681 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@7f88772b
[junit4:junit4]   2> 12683 T1978 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 12683 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 12683 T1978 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 12683 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44144
[junit4:junit4]   2> 12684 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44144
[junit4:junit4]   2> 12684 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d23810389000a with negotiated timeout 20000 for client /127.0.0.1:44144
[junit4:junit4]   2> 12685 T1978 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d23810389000a, negotiated timeout = 20000
[junit4:junit4]   2> 12685 T1979 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7f88772b name:ZooKeeperConnection Watcher:127.0.0.1:40687/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 12686 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 12688 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d23810389000a type:create cxid:0x1 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12689 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d23810389000a type:create cxid:0x2 zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 12690 T1908 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 13692 T1908 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55295_
[junit4:junit4]   2> 13693 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d23810389000a type:delete cxid:0x9 zxid:0x91 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:55295_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:55295_
[junit4:junit4]   2> 13693 T1908 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55295_
[junit4:junit4]   2> 13694 T1930 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> 13695 T1936 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> 13695 T1949 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13695 T1964 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> 13695 T1949 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> 13695 T1979 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13696 T1930 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13697 T1936 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13697 T1964 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 13700 T1980 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.BasicDistributedZk2Test-jetty3-1362100302991/collection1
[junit4:junit4]   2> 13700 T1980 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 13701 T1980 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 13701 T1980 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 13702 T1980 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.BasicDistributedZk2Test-jetty3-1362100302991/collection1/'
[junit4:junit4]   2> 13702 T1980 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362100302991/collection1/lib/README' to classloader
[junit4:junit4]   2> 13702 T1980 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1362100302991/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 13729 T1980 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 13760 T1980 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 13760 T1980 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 13764 T1980 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 14030 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 14030 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":"3",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:47300",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:47300_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 14034 T1936 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> 14034 T1964 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> 14035 T1979 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> 14034 T1930 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> 14034 T1949 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> 14079 T1980 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 14084 T1980 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 14086 T1980 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 14097 T1980 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 14100 T1980 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 14103 T1980 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 14103 T1980 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 14104 T1980 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.BasicDistributedZk2Test-jetty3-1362100302991/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/
[junit4:junit4]   2> 14104 T1980 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c7fa1a
[junit4:junit4]   2> 14105 T1980 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 14105 T1980 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.BasicDistributedZk2Test-1362100290437/jetty3 forceNew:false
[junit4:junit4]   2> 14106 T1980 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3
[junit4:junit4]   2> 14106 T1980 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index/
[junit4:junit4]   2> 14106 T1980 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 14107 T1980 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.BasicDistributedZk2Test-1362100290437/jetty3/index forceNew:false
[junit4:junit4]   2> 14110 T1980 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@68ec5b65; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14110 T1980 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 14111 T1980 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index
[junit4:junit4]   2> 14112 T1980 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 14113 T1980 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 14113 T1980 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 14114 T1980 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 14114 T1980 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 14114 T1980 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 14115 T1980 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 14115 T1980 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 14115 T1980 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 14118 T1980 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 14121 T1980 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3
[junit4:junit4]   2> 14122 T1980 oass.SolrIndexSearcher.<init> Opening Searcher@7914b4af main
[junit4:junit4]   2> 14123 T1980 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 14123 T1980 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 14127 T1981 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7914b4af main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 14128 T1980 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 14129 T1980 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 15538 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 15539 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55295",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55295_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 15540 T1931 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 15540 T1931 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4:junit4]   2> 15545 T1979 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> 15545 T1936 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> 15545 T1964 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> 15545 T1949 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> 15545 T1930 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> 16130 T1980 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 16131 T1980 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55295 collection:collection1 shard:shard2
[junit4:junit4]   2> 16134 T1980 oasc.ZkController.register We are http://127.0.0.1:55295/collection1/ and leader is http://127.0.0.1:42736/collection1/
[junit4:junit4]   2> 16134 T1980 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55295
[junit4:junit4]   2> 16135 T1980 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 16135 T1980 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C192 name=collection1 org.apache.solr.core.SolrCore@72992695 url=http://127.0.0.1:55295/collection1 node=127.0.0.1:55295_ C192_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, base_url=http://127.0.0.1:55295, state=down, node_name=127.0.0.1:55295_}
[junit4:junit4]   2> 16136 T1982 C192 P55295 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 16136 T1980 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 16138 T1982 C192 P55295 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 16138 T1982 C192 P55295 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 16138 T1982 C192 P55295 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 16139 T1908 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 16139 T1908 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 16140 T1982 C192 P55295 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16140 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 16209 T1908 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 16211 T1908 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:36524
[junit4:junit4]   2> 16212 T1908 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 16212 T1908 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 16213 T1908 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.BasicDistributedZk2Test-jetty4-1362100306580
[junit4:junit4]   2> 16213 T1908 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.BasicDistributedZk2Test-jetty4-1362100306580/solr.xml
[junit4:junit4]   2> 16214 T1908 oasc.CoreContainer.<init> New CoreContainer 991444952
[junit4:junit4]   2> 16214 T1908 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.BasicDistributedZk2Test-jetty4-1362100306580/'
[junit4:junit4]   2> 16215 T1908 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.BasicDistributedZk2Test-jetty4-1362100306580/'
[junit4:junit4]   2> 16234 T1908 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4:junit4]   2> 16234 T1908 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 16235 T1908 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 16235 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 16236 T1908 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 16236 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 16237 T1908 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 16237 T1908 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 16237 T1908 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 16238 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   2> 16244 T1908 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 16254 T1908 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:40687/solr
[junit4:junit4]   2> 16255 T1908 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 16255 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@6de68b1f
[junit4:junit4]   2> 16256 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 16256 T1992 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 16257 T1992 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 16257 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44147
[junit4:junit4]   2> 16257 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44147
[junit4:junit4]   2> 16258 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d23810389000b with negotiated timeout 20000 for client /127.0.0.1:44147
[junit4:junit4]   2> 16258 T1992 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d23810389000b, negotiated timeout = 20000
[junit4:junit4]   2> 16258 T1993 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6de68b1f name:ZooKeeperConnection Watcher:127.0.0.1:40687 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16258 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 16260 T1913 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13d23810389000b
[junit4:junit4]   2> 16260 T1993 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 16260 T1910 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:44147 which had sessionid 0x13d23810389000b
[junit4:junit4]   2> 16260 T1908 oaz.ZooKeeper.close Session: 0x13d23810389000b closed
[junit4:junit4]   2> 16261 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   2> 16263 T1908 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:40687/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@6f6b0206
[junit4:junit4]   2> 16264 T1994 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server localhost.localdomain/127.0.0.1:40687. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 16264 T1908 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 16265 T1910 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:44148
[junit4:junit4]   2> 16265 T1994 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:40687, initiating session
[junit4:junit4]   2> 16266 T1910 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:44148
[junit4:junit4]   2> 16268 T1912 oazs.ZooKeeperServer.finishSessionInit Established session 0x13d23810389000c with negotiated timeout 20000 for client /127.0.0.1:44148
[junit4:junit4]   2> 16268 T1994 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server localhost.localdomain/127.0.0.1:40687, sessionid = 0x13d23810389000c, negotiated timeout = 20000
[junit4:junit4]   2> 16269 T1995 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6f6b0206 name:ZooKeeperConnection Watcher:127.0.0.1:40687/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16269 T1908 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 16270 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d23810389000c type:create cxid:0x1 zxid:0xa2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16271 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d23810389000c type:create cxid:0x2 zxid:0xa3 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 16271 T1908 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 17050 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 17051 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55295",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55295_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 17055 T1995 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> 17055 T1964 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> 17056 T1949 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> 17055 T1979 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> 17055 T1936 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> 17055 T1930 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> 17273 T1908 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36524_
[junit4:junit4]   2> 17275 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d23810389000c type:delete cxid:0xb zxid:0xa8 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:36524_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:36524_
[junit4:junit4]   2> 17276 T1908 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36524_
[junit4:junit4]   2> 17278 T1979 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> 17278 T1930 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> 17278 T1936 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> 17279 T1995 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17279 T1949 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17280 T1949 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> 17278 T1964 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> 17279 T1995 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> 17281 T1979 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17281 T1930 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17281 T1936 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17283 T1964 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 17324 T1996 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.BasicDistributedZk2Test-jetty4-1362100306580/collection1
[junit4:junit4]   2> 17324 T1996 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 17324 T1996 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 17325 T1996 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 17325 T1996 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.BasicDistributedZk2Test-jetty4-1362100306580/collection1/'
[junit4:junit4]   2> 17326 T1996 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1362100306580/collection1/lib/README' to classloader
[junit4:junit4]   2> 17326 T1996 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1362100306580/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 17362 T1996 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 17394 T1996 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 17395 T1996 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 17398 T1996 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 17721 T1996 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 17726 T1996 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 17728 T1996 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 17735 T1996 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17737 T1996 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17740 T1996 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17741 T1996 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17741 T1996 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.BasicDistributedZk2Test-jetty4-1362100306580/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/
[junit4:junit4]   2> 17741 T1996 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c7fa1a
[junit4:junit4]   2> 17742 T1996 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 17742 T1996 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.BasicDistributedZk2Test-1362100290437/jetty4 forceNew:false
[junit4:junit4]   2> 17742 T1996 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4
[junit4:junit4]   2> 17743 T1996 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index/
[junit4:junit4]   2> 17743 T1996 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 17743 T1996 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.BasicDistributedZk2Test-1362100290437/jetty4/index forceNew:false
[junit4:junit4]   2> 17746 T1996 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5b73abb3; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 17746 T1996 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 17747 T1996 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index
[junit4:junit4]   2> 17748 T1996 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 17748 T1996 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 17749 T1996 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 17749 T1996 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 17749 T1996 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 17749 T1996 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 17750 T1996 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 17750 T1996 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 17750 T1996 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 17753 T1996 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 17755 T1996 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4
[junit4:junit4]   2> 17756 T1996 oass.SolrIndexSearcher.<init> Opening Searcher@6ead1915 main
[junit4:junit4]   2> 17757 T1996 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 17757 T1996 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 17759 T1997 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6ead1915 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 17760 T1996 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 17760 T1996 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 18562 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18562 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":"5",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:36524",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:36524_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 18563 T1931 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4:junit4]   2> 18563 T1931 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 18566 T1964 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> 18566 T1995 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> 18566 T1979 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> 18566 T1930 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> 18566 T1936 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> 18567 T1949 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> 18761 T1996 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 18762 T1996 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:36524 collection:collection1 shard:shard1
[junit4:junit4]   2> 18765 T1996 oasc.ZkController.register We are http://127.0.0.1:36524/collection1/ and leader is http://127.0.0.1:47300/collection1/
[junit4:junit4]   2> 18765 T1996 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:36524
[junit4:junit4]   2> 18766 T1996 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 18766 T1996 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C193 name=collection1 org.apache.solr.core.SolrCore@29e52fe8 url=http://127.0.0.1:36524/collection1 node=127.0.0.1:36524_ C193_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, base_url=http://127.0.0.1:36524, state=down, node_name=127.0.0.1:36524_}
[junit4:junit4]   2> 18767 T1998 C193 P36524 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 18767 T1996 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18767 T1998 C193 P36524 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 18768 T1998 C193 P36524 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 18768 T1998 C193 P36524 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 18769 T1908 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4:junit4]   2> 18769 T1998 C193 P36524 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18770 T1908 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 18770 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18776 T1908 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 18778 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 18785 T1944 oasc.CoreContainer.create Creating SolrCore 'onenodecollectioncore' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362100293974/onenodecollectioncore
[junit4:junit4]   2> 18785 T1944 oasc.ZkController.createCollectionZkNode Check for collection zkNode:onenodecollection
[junit4:junit4]   2> 18786 T1944 oasc.ZkController.createCollectionZkNode Creating collection in ZooKeeper:onenodecollection
[junit4:junit4]   2> 18787 T1944 oasc.ZkController.getConfName Looking for collection configName
[junit4:junit4]   2> 18788 T1944 oasc.ZkController.getConfName Only one config set found in zk - using it:conf1
[junit4:junit4]   2> 18789 T1944 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection
[junit4:junit4]   2> 18791 T1944 oasc.ZkController.readConfigName Load collection config from:/collections/onenodecollection
[junit4:junit4]   2> 18793 T1944 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.BasicDistributedZk2Test-jetty1-1362100293974/onenodecollectioncore/'
[junit4:junit4]   2> 18829 T1944 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_42
[junit4:junit4]   2> 18858 T1944 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 18858 T1944 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 18862 T1944 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2>  C192_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, base_url=http://127.0.0.1:55295, state=recovering, shard=shard2, node_name=127.0.0.1:55295_}
[junit4:junit4]   2> 19149 T1982 C192 P55295 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:42736/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 19150 T1982 C192 P55295 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:55295 START replicas=[http://127.0.0.1:42736/collection1/] nUpdates=100
[junit4:junit4]   2> 19150 T1982 C192 P55295 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 19150 T1982 C192 P55295 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 19150 T1982 C192 P55295 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 19150 T1982 C192 P55295 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 19150 T1982 C192 P55295 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 19150 T1982 C192 P55295 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:42736/collection1/. core=collection1
[junit4:junit4]   2> 19151 T1982 C192 P55295 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C194 name=collection1 org.apache.solr.core.SolrCore@19cb4bc7 url=http://127.0.0.1:42736/collection1 node=127.0.0.1:42736_ C194_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, base_url=http://127.0.0.1:42736, state=active, shard=shard2, node_name=127.0.0.1:42736_, leader=true}
[junit4:junit4]   2> 19154 T1943 C194 P42736 REQ /get {version=2&distrib=false&wt=javabin&getVersions=100&qt=/get} status=0 QTime=0 
[junit4:junit4]   2> 19156 T1942 C194 P42736 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 19157 T1942 C194 P42736 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1
[junit4:junit4]   2> 19158 T1942 C194 P42736 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2da9f82f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 19159 T1942 C194 P42736 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 19167 T1942 C194 P42736 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2da9f82f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2da9f82f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19168 T1942 C194 P42736 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19169 T1942 C194 P42736 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1
[junit4:junit4]   2> 19169 T1942 C194 P42736 oass.SolrIndexSearcher.<init> Opening Searcher@559e77ad realtime
[junit4:junit4]   2> 19170 T1942 C194 P42736 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 19170 T1942 C194 P42736 /update {commit_end_point=true&version=2&softCommit=false&wt=javabin&commit=true&openSearcher=false&waitSearcher=true} {commit=} 0 15
[junit4:junit4]   2> 19171 T1982 C192 P55295 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 19171 T1982 C192 P55295 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 19175 T1945 C194 P42736 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 19175 T1945 C194 P42736 REQ /replication {version=2&command=indexversion&wt=javabin&qt=/replication} status=0 QTime=3 
[junit4:junit4]   2> 19176 T1982 C192 P55295 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 19176 T1982 C192 P55295 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 19176 T1982 C192 P55295 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 19178 T1942 C194 P42736 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1
[junit4:junit4]   2> 19179 T1942 C194 P42736 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index
[junit4:junit4]   2> 19179 T1942 C194 P42736 REQ /replication {version=2&command=filelist&generation=2&wt=javabin&qt=/replication} status=0 QTime=1 
[junit4:junit4]   2> 19180 T1982 C192 P55295 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 19180 T1982 C192 P55295 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.BasicDistributedZk2Test-1362100290437/jetty3/index.20130301091149614 forceNew:false
[junit4:junit4]   2> 19181 T1982 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3
[junit4:junit4]   2> 19181 T1982 C192 P55295 oash.SnapPuller.fetchLatestIndex Starting download to NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index.20130301091149614 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a386a40; maxCacheMB=48.0 maxMergeSizeMB=4.0) fullCopy=true
[junit4:junit4]   2> 19184 T1943 C194 P42736 REQ /replication {checksum=true&file=segments_2&command=filecontent&generation=2&wt=filestream&qt=/replication} status=0 QTime=0 
[junit4:junit4]   2> 19217 T1982 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index
[junit4:junit4]   2> 19217 T1982 C192 P55295 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 19227 T1982 C192 P55295 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130301091149614
[junit4:junit4]   2> 19228 T1982 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3
[junit4:junit4]   2> 19229 T1982 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3
[junit4:junit4]   2> 19229 T1982 C192 P55295 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index/ new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index.20130301091149614
[junit4:junit4]   2> 19231 T1982 C192 P55295 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index.20130301091149614 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a386a40; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19232 T1982 C192 P55295 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19232 T1982 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3
[junit4:junit4]   2> 19233 T1982 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3
[junit4:junit4]   2> 19234 T1982 C192 P55295 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 19234 T1982 C192 P55295 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 19234 T1982 C192 P55295 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 19236 T1982 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index.20130301091149614
[junit4:junit4]   2> 19237 T1982 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3
[junit4:junit4]   2> 19238 T1944 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 19240 T1982 C192 P55295 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index.20130301091149614 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a386a40; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 19240 T1982 C192 P55295 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 19240 T1982 C192 P55295 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 19241 T1982 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3
[junit4:junit4]   2> 19241 T1982 C192 P55295 oass.SolrIndexSearcher.<init> Opening Searcher@32a44a03 main
[junit4:junit4]   2> 19242 T1981 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@32a44a03 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 19246 T1981 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index
[junit4:junit4]   2> 19248 T1944 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 19250 T1982 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index.20130301091149614
[junit4:junit4]   2> 19251 T1982 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index
[junit4:junit4]   2> 19251 T1982 C192 P55295 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 19251 T1982 C192 P55295 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 19251 T1982 C192 P55295 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 19251 T1982 C192 P55295 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 19253 T1944 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 19257 T1982 C192 P55295 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 19268 T1944 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 19273 T1944 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 19335 T1944 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 19337 T1944 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 19351 T1944 oasc.SolrCore.<init> [onenodecollectioncore] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1362100293974/onenodecollectioncore/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362100290437/onenodecollection/
[junit4:junit4]   2> 19351 T1944 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c7fa1a
[junit4:junit4]   2> 19352 T1944 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 19353 T1944 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362100290437/onenodecollection forceNew:false
[junit4:junit4]   2> 19353 T1944 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362100290437/onenodecollection
[junit4:junit4]   2> 19353 T1944 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-BasicDistributedZk2Test-1362100290437/onenodecollection/index/
[junit4:junit4]   2> 19354 T1944 oasc.SolrCore.initIndex WARNING [onenodecollectioncore] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362100290437/onenodecollection/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 19354 T1944 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362100290437/onenodecollection/index forceNew:false
[junit4:junit4]   2> 19358 T1944 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/solrtest-BasicDistributedZk2Test-1362100290437/onenodecollection/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@a388fa3; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 19358 T1944 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 19359 T1944 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362100290437/onenodecollection/index
[junit4:junit4]   2> 19361 T1944 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 19361 T1944 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 19362 T1944 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 19362 T1944 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 19363 T1944 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 19363 T1944 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 19364 T1944 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 19364 T1944 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 19364 T1944 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 19367 T1944 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 19369 T1944 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZk2Test-1362100290437/onenodecollection
[junit4:junit4]   2> 19370 T1944 oass.SolrIndexSearcher.<init> Opening Searcher@dedb4ab main
[junit4:junit4]   2> 19371 T1944 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 19372 T1944 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 19374 T2001 oasc.SolrCore.registerSearcher [onenodecollectioncore] Registered new searcher Searcher@dedb4ab main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 19376 T1944 oasc.ZkController.publish publishing core=onenodecollectioncore state=down
[junit4:junit4]   2> 20070 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 20071 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":"5",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:36524",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:36524_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 20073 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":"4",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:55295",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard2",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:55295_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 20076 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "core":"onenodecollectioncore",
[junit4:junit4]   2> 	  "collection":"onenodecollection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42736",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42736_",
[junit4:junit4]   2> 	  "roles":"none"}
[junit4:junit4]   2> 20076 T1931 oasc.Overseer$ClusterStateUpdater.createCollection Create collection onenodecollection with numShards 1
[junit4:junit4]   2> 20076 T1931 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 20078 T1979 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> 20078 T1930 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> 20079 T1936 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> 20079 T1949 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> 20078 T1995 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> 20079 T1964 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> 20377 T1944 oasc.CoreContainer.registerCore registering core: onenodecollectioncore
[junit4:junit4]   2> 20378 T1944 oasc.ZkController.register Register replica - core:onenodecollectioncore address:http://127.0.0.1:42736 collection:onenodecollection shard:shard1
[junit4:junit4]   2> 20379 T1944 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leader_elect/shard1/election
[junit4:junit4]   2> 20382 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890006 type:delete cxid:0xa6 zxid:0xc3 txntype:-1 reqpath:n/a Error Path:/solr/collections/onenodecollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/onenodecollection/leaders
[junit4:junit4]   2> 20383 T1944 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 20383 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890006 type:create cxid:0xa7 zxid:0xc4 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 20385 T1944 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 20385 T1944 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 20385 T1944 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:42736/onenodecollectioncore/
[junit4:junit4]   2> 20386 T1944 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 20386 T1944 oasc.SyncStrategy.syncToMe http://127.0.0.1:42736/onenodecollectioncore/ has no replicas
[junit4:junit4]   2> 20386 T1944 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:42736/onenodecollectioncore/
[junit4:junit4]   2> 20387 T1944 oascc.SolrZkClient.makePath makePath: /collections/onenodecollection/leaders/shard1
[junit4:junit4]   2> 20389 T1913 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13d238103890006 type:create cxid:0xb1 zxid:0xc8 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 21584 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21593 T1995 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> 21593 T1979 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> 21593 T1930 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> 21593 T1964 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> 21593 T1936 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> 21593 T1949 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> 21642 T1944 oasc.ZkController.register We are http://127.0.0.1:42736/onenodecollectioncore/ and leader is http://127.0.0.1:42736/onenodecollectioncore/
[junit4:junit4]   2> 21643 T1944 oasc.ZkController.register No LogReplay needed for core=onenodecollectioncore baseURL=http://127.0.0.1:42736
[junit4:junit4]   2> 21644 T1944 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 21644 T1944 oasc.ZkController.publish publishing core=onenodecollectioncore state=active
[junit4:junit4]   2> 21647 T1944 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 21649 T1908 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: onenodecollection failOnTimeout:true timeout (sec):230
[junit4:junit4]   2> 21650 T1908 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 22652 T1908 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C193_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, base_url=http://127.0.0.1:36524, state=recovering, shard=shard1, node_name=127.0.0.1:36524_}
[junit4:junit4]   2> 22778 T1998 C193 P36524 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:47300/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 22778 T1998 C193 P36524 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:36524 START replicas=[http://127.0.0.1:47300/collection1/] nUpdates=100
[junit4:junit4]   2> 22778 T1998 C193 P36524 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 22779 T1998 C193 P36524 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 22779 T1998 C193 P36524 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 22779 T1998 C193 P36524 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 22779 T1998 C193 P36524 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 22779 T1998 C193 P36524 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:47300/collection1/. core=collection1
[junit4:junit4]   2> 22780 T1998 C193 P36524 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C195 name=collection1 org.apache.solr.core.SolrCore@2ab7f9f7 url=http://127.0.0.1:47300/collection1 node=127.0.0.1:47300_ C195_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, base_url=http://127.0.0.1:47300, state=active, shard=shard1, node_name=127.0.0.1:47300_, leader=true}
[junit4:junit4]   2> 22787 T1958 C195 P47300 REQ /get {version=2&distrib=false&wt=javabin&getVersions=100&qt=/get} status=0 QTime=3 
[junit4:junit4]   2> 22788 T1959 C195 P47300 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 22789 T1959 C195 P47300 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2
[junit4:junit4]   2> 22791 T1959 C195 P47300 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@45b764fb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 22792 T1959 C195 P47300 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 22798 T1959 C195 P47300 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@45b764fb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@45b764fb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 22798 T1959 C195 P47300 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 22799 T1959 C195 P47300 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2
[junit4:junit4]   2> 22800 T1959 C195 P47300 oass.SolrIndexSearcher.<init> Opening Searcher@2ca0a99c realtime
[junit4:junit4]   2> 22800 T1959 C195 P47300 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 22801 T1959 C195 P47300 /update {commit_end_point=true&version=2&softCommit=false&wt=javabin&commit=true&openSearcher=false&waitSearcher=true} {commit=} 0 14
[junit4:junit4]   2> 22801 T1998 C193 P36524 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 22802 T1998 C193 P36524 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 22804 T1957 C195 P47300 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 22804 T1957 C195 P47300 REQ /replication {version=2&command=indexversion&wt=javabin&qt=/replication} status=0 QTime=1 
[junit4:junit4]   2> 22805 T1998 C193 P36524 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 22805 T1998 C193 P36524 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 22805 T1998 C193 P36524 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 22807 T1958 C195 P47300 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2
[junit4:junit4]   2> 22808 T1958 C195 P47300 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/index
[junit4:junit4]   2> 22808 T1958 C195 P47300 REQ /replication {version=2&command=filelist&generation=2&wt=javabin&qt=/replication} status=0 QTime=1 
[junit4:junit4]   2> 22808 T1998 C193 P36524 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 22809 T1998 C193 P36524 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.BasicDistributedZk2Test-1362100290437/jetty4/index.20130301091153243 forceNew:false
[junit4:junit4]   2> 22809 T1998 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4
[junit4:junit4]   2> 22810 T1998 C193 P36524 oash.SnapPuller.fetchLatestIndex Starting download to NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index.20130301091153243 lockFactory=org.apache.lucene.store.NativeFSLockFactory@44a000f4; maxCacheMB=48.0 maxMergeSizeMB=4.0) fullCopy=true
[junit4:junit4]   2> 22813 T1959 C195 P47300 REQ /replication {checksum=true&file=segments_2&command=filecontent&generation=2&wt=filestream&qt=/replication} status=0 QTime=0 
[junit4:junit4]   2> 22815 T1998 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index
[junit4:junit4]   2> 22815 T1998 C193 P36524 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 22817 T1998 C193 P36524 oash.SnapPuller.modifyIndexProps New index installed. Updating index properties... index=index.20130301091153243
[junit4:junit4]   2> 22818 T1998 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4
[junit4:junit4]   2> 22819 T1998 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4
[junit4:junit4]   2> 22819 T1998 C193 P36524 oasc.SolrCore.getNewIndexDir New index directory detected: old=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index/ new=./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index.20130301091153243
[junit4:junit4]   2> 22820 T1998 C193 P36524 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index.20130301091153243 lockFactory=org.apache.lucene.store.NativeFSLockFactory@44a000f4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 22821 T1998 C193 P36524 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 22821 T1998 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4
[junit4:junit4]   2> 22822 T1998 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4
[junit4:junit4]   2> 22822 T1998 C193 P36524 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 22822 T1998 C193 P36524 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 22822 T1998 C193 P36524 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 22823 T1998 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index.20130301091153243
[junit4:junit4]   2> 22823 T1998 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4
[junit4:junit4]   2> 22825 T1998 C193 P36524 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index.20130301091153243 lockFactory=org.apache.lucene.store.NativeFSLockFactory@44a000f4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 22826 T1998 C193 P36524 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 22826 T1998 C193 P36524 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 22826 T1998 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4
[junit4:junit4]   2> 22827 T1998 C193 P36524 oass.SolrIndexSearcher.<init> Opening Searcher@613c146c main
[junit4:junit4]   2> 22828 T1997 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@613c146c main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 22828 T1997 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index
[junit4:junit4]   2> 22828 T1998 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index.20130301091153243
[junit4:junit4]   2> 22829 T1998 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index
[junit4:junit4]   2> 22829 T1998 C193 P36524 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 22829 T1998 C193 P36524 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 22829 T1998 C193 P36524 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 22830 T1998 C193 P36524 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 22831 T1998 C193 P36524 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 23099 T1931 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 23100 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "core_node_name":null,
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "core":"onenodecollectioncore",
[junit4:junit4]   2> 	  "collection":"onenodecollection",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:42736",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:42736_",
[junit4:junit4]   2> 	  "roles":"none"}
[junit4:junit4]   2> 23102 T1931 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4:junit4]   2> 	  "core_node_name":"5",
[junit4:junit4]   2> 	  "numShards":"2",
[junit4:junit4]   2> 	  "core":"collection1",
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:36524",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:36524_",
[junit4:junit4]   2> 	  "roles":null}
[junit4:junit4]   2> 23104 T1979 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> 23105 T1964 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> 23105 T1930 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> 23105 T1949 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> 23104 T1936 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> 23104 T1995 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> 23654 T1908 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 23655 T1908 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: onenodecollection
[junit4:junit4]   2> 23656 T1908 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2>  C193_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, base_url=http://127.0.0.1:36524, state=active, shard=shard1, node_name=127.0.0.1:36524_}
[junit4:junit4]   2> 23680 T1989 C193 P36524 /update {version=2&wt=javabin&distrib.from=http://127.0.0.1:47300/collection1/&update.distrib=FROMLEADER} {add=[1 (1428265698966110208)]} 0 1
[junit4:junit4]   2> 23681 T1957 C195 P47300 /update {version=2&wt=javabin&distrib.from=http://127.0.0.1:42736/collection1/&update.distrib=TOLEADER} {add=[1 (1428265698966110208)]} 0 8
[junit4:junit4]   2> 23681 T1943 C194 P42736 /update {version=2&wt=javabin} {add=[1]} 0 12
[junit4:junit4]   2> 23689 T1945 C194 P42736 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 23697 T1945 C194 P42736 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2da9f82f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@2da9f82f; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 23698 T1945 C194 P42736 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 23699 T1945 C194 P42736 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1
[junit4:junit4]   2> 23699 T1945 C194 P42736 oass.SolrIndexSearcher.<init> Opening Searcher@491c7056 main
[junit4:junit4]   2> 23699 T1945 C194 P42736 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index
[junit4:junit4]   2> 23700 T1945 C194 P42736 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23700 T1951 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@491c7056 main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 23700 T1951 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty1/index
[junit4:junit4]   2> 23701 T1945 C194 P42736 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:55295/collection1/, StdNode: http://127.0.0.1:47300/collection1/, StdNode: http://127.0.0.1:36524/collection1/] params:commit_end_point=true&commit=true&softCommit=false&waitSearcher=true&expungeDeletes=false
[junit4:junit4]   2> 23703 T1990 C193 P36524 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 23704 T1960 C195 P47300 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2>  C192_STATE=coll:collection1 core:collection1 props:{core=collection1, collection=collection1, base_url=http://127.0.0.1:55295, state=active, shard=shard2, node_name=127.0.0.1:55295_}
[junit4:junit4]   2> 23712 T1973 C192 P55295 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   2> 23716 T1973 C192 P55295 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index.20130301091149614 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a386a40; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index.20130301091149614 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5a386a40; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[segments_3]
[junit4:junit4]   2> 23717 T1973 C192 P55295 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[segments_3]
[junit4:junit4]   2> 23720 T1973 C192 P55295 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3
[junit4:junit4]   2> 23721 T1973 C192 P55295 oass.SolrIndexSearcher.<init> Opening Searcher@6e16cf7a main
[junit4:junit4]   2> 23721 T1973 C192 P55295 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23722 T1981 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6e16cf7a main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   2> 23722 T1981 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty3/index.20130301091149614
[junit4:junit4]   2> 23723 T1973 C192 P55295 /update {commit_end_point=true&version=2&softCommit=false&wt=javabin&commit=true&expungeDeletes=false&waitSearcher=true} {commit=} 0 11
[junit4:junit4]   2> 23727 T1990 C193 P36524 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index.20130301091153243 lockFactory=org.apache.lucene.store.NativeFSLockFactory@44a000f4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index.20130301091153243 lockFactory=org.apache.lucene.store.NativeFSLockFactory@44a000f4; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[_0.len, segments_3, _0.inf, _0.si, _0.fld, _0.pst]
[junit4:junit4]   2> 23728 T1990 C193 P36524 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0.len, segments_3, _0.inf, _0.si, _0.fld, _0.pst]
[junit4:junit4]   2> 23730 T1990 C193 P36524 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4
[junit4:junit4]   2> 23732 T1990 C193 P36524 oass.SolrIndexSearcher.<init> Opening Searcher@78e49468 main
[junit4:junit4]   2> 23732 T1990 C193 P36524 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23733 T1997 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@78e49468 main{StandardDirectoryReader(segments_3:3:nrt _0(4.2):C1)}
[junit4:junit4]   2> 23733 T1997 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty4/index.20130301091153243
[junit4:junit4]   2> 23733 T1990 C193 P36524 /update {commit_end_point=true&version=2&softCommit=false&wt=javabin&commit=true&expungeDeletes=false&waitSearcher=true} {commit=} 0 30
[junit4:junit4]   2> 23737 T1960 C195 P47300 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@45b764fb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@45b764fb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[_0.len, segments_3, _0.inf, _0.si, _0.fld, _0.pst]
[junit4:junit4]   2> 23738 T1960 C195 P47300 oasc.SolrDeletionPolicy.updateCommits newest commit = 3[_0.len, segments_3, _0.inf, _0.si, _0.fld, _0.pst]
[junit4:junit4]   2> 23739 T1960 C195 P47300 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2
[junit4:junit4]   2> 23740 T1960 C195 P47300 oass.SolrIndexSearcher.<init> Opening Searcher@127a187a main
[junit4:junit4]   2> 23740 T1960 C195 P47300 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZk2Test-1362100290437/jetty2/index
[junit4:junit4]   2> 23741 T1960 C195 P47300 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 23741 T1966 oasc.SolrCore.registerSearcher [collection1

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

    > 	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> 32403 T1908 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 32409 T1907 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4:junit4]   2> 32495 T1979 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 32496 T1979 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> 32496 T1979 oascc.ZkStateReader$2.process WARNING ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   2> 32496 T1979 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 32566 T1911 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> NOTE: test params are: codec=SimpleText, sim=DefaultSimilarity, locale=es_PR, timezone=PRC
[junit4:junit4]   2> NOTE: Linux 3.2.0-38-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=2,free=230592008,total=432013312
[junit4:junit4]   2> NOTE: All tests run in this JVM: [WordBreakSolrSpellCheckerTest, TestComponentsName, SimpleFacetsTest, TestIndexingPerformance, TestPHPSerializedResponseWriter, TestReload, BadComponentTest, LoggingHandlerTest, TestSearchPerf, LeaderElectionIntegrationTest, IndexBasedSpellCheckerTest, TestRandomFaceting, TestQueryTypes, SpellPossibilityIteratorTest, TestSolrIndexConfig, TestDocumentBuilder, TestFastLRUCache, BinaryUpdateRequestHandlerTest, SOLR749Test, NoCacheHeaderTest, ChaosMonkeyNothingIsSafeTest, TestGroupingSearch, ScriptEngineTest, MBeansHandlerTest, TestStressVersions, AlternateDirectoryTest, DateMathParserTest, CoreAdminHandlerTest, MinimalSchemaTest, CSVRequestHandlerTest, SolrCoreCheckLockOnStartupTest, UpdateRequestProcessorFactoryTest, ClusterStateTest, TestWordDelimiterFilterFactory, ZkControllerTest, TestIBSimilarityFactory, JsonLoaderTest, CoreContainerCoreInitFailuresTest, DistributedSpellCheckComponentTest, TestElisionMultitermQuery, SolrCmdDistributorTest, JSONWriterTest, TestCoreContainer, TestSuggestSpellingConverter, NotRequiredUniqueKeyTest, DefaultValueUpdateProcessorTest, DirectUpdateHandlerOptimizeTest, TestBadConfig, RegexBoostProcessorTest, SolrRequestParserTest, CurrencyFieldTest, HighlighterConfigTest, DirectSolrConnectionTest, TestSystemIdResolver, CacheHeaderTest, TestFiltering, UniqFieldsUpdateProcessorFactoryTest, TestAnalyzedSuggestions, PreAnalyzedFieldTest, SolrIndexSplitterTest, AutoCommitTest, NumericFieldsTest, BasicDistributedZkTest, TestRecovery, TestRangeQuery, FileBasedSpellCheckerTest, ConvertedLegacyTest, QueryElevationComponentTest, SortByFunctionTest, SignatureUpdateProcessorFactoryTest, TestBinaryResponseWriter, SampleTest, TestIndexSearcher, TestFunctionQuery, TestRemoteStreaming, PolyFieldTest, SpatialFilterTest, TestCSVResponseWriter, MoreLikeThisHandlerTest, DocumentBuilderTest, DistributedQueryElevationComponentTest, TestPseudoReturnFields, DisMaxRequestHandlerTest, TermsComponentTest, DistanceFunctionTest, XsltUpdateRequestHandlerTest, FieldAnalysisRequestHandlerTest, PingRequestHandlerTest, SuggesterWFSTTest, TestSurroundQueryParser, IndexSchemaRuntimeFieldTest, TestSolrQueryParser, SolrPluginUtilsTest, TestValueSourceCache, FastVectorHighlighterTest, TestReversedWildcardFilterFactory, DebugComponentTest, RequestHandlersTest, TestOmitPositions, CopyFieldTest, TestArbitraryIndexDir, UpdateParamsTest, TestSolrDeletionPolicy1, TestLuceneMatchVersion, TestPropInject, TestBinaryField, TestSolrDeletionPolicy2, TestLFUCache, TestQuerySenderNoQuery, TestNumberUtils, TestJmxMonitoredMap, TestConfig, TestPhraseSuggestions, TestPerFieldSimilarity, TestDFRSimilarityFactory, TimeZoneUtilsTest, PluginInfoTest, TestSolrXMLSerializer, UUIDFieldTest, SystemInfoHandlerTest, TestUtils, ZkNodePropsTest, CircularListTest, AnalysisAfterCoreReloadTest, TestRandomDVFaceting, PathHierarchyTokenizerFactoryTest, TestCharFilters, BasicDistributedZk2Test]
[junit4:junit4] Completed on J0 in 32.71s, 1 test, 1 failure <<< FAILURES!

[...truncated 92 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:1207: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:870: There were test failures: 258 suites, 1078 tests, 1 failure, 8 ignored (3 assumptions)

Total time: 41 minutes 31 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