You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/10/24 23:45:01 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.7.0) - Build # 944 -
Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/944/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.testDistribSearch
Error Message:
No live SolrServers available to handle this request:[http://127.0.0.1:49723/collection1, http://127.0.0.1:49756/collection1, http://127.0.0.1:49771/collection1, http://127.0.0.1:49761/collection1, http://127.0.0.1:49720/collection1, http://127.0.0.1:49717/collection1, http://127.0.0.1:49730/collection1, http://127.0.0.1:49744/collection1, http://127.0.0.1:49766/collection1, http://127.0.0.1:49751/collection1, http://127.0.0.1:49726/collection1, http://127.0.0.1:49737/collection1]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:49723/collection1, http://127.0.0.1:49756/collection1, http://127.0.0.1:49771/collection1, http://127.0.0.1:49761/collection1, http://127.0.0.1:49720/collection1, http://127.0.0.1:49717/collection1, http://127.0.0.1:49730/collection1, http://127.0.0.1:49744/collection1, http://127.0.0.1:49766/collection1, http://127.0.0.1:49751/collection1, http://127.0.0.1:49726/collection1, http://127.0.0.1:49737/collection1]
at __randomizedtesting.SeedInfo.seed([6C5B384E6F801A63:EDBDB65618DF7A5F]:0)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:337)
at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:640)
at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1158)
at org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.doTest(ChaosMonkeySafeLeaderTest.java:135)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
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:606)
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:744)
Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:49730/collection1
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:476)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:268)
... 46 more
Caused by: java.net.SocketException: Bad file descriptor
at java.net.SocketOutputStream.socketWrite0(Native Method)
at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:113)
at java.net.SocketOutputStream.write(SocketOutputStream.java:159)
at org.apache.http.impl.io.AbstractSessionOutputBuffer.flushBuffer(AbstractSessionOutputBuffer.java:147)
at org.apache.http.impl.io.AbstractSessionOutputBuffer.flush(AbstractSessionOutputBuffer.java:154)
at org.apache.http.impl.AbstractHttpClientConnection.doFlush(AbstractHttpClientConnection.java:278)
at org.apache.http.impl.AbstractHttpClientConnection.flush(AbstractHttpClientConnection.java:283)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.flush(ManagedClientConnectionImpl.java:175)
at org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:260)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:715)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:520)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:366)
... 48 more
Build Log:
[...truncated 9834 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest
[junit4] 2> 963164 T1722 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 963205 T1722 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ChaosMonkeySafeLeaderTest-1382648095912
[junit4] 2> 963207 T1722 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 963217 T1723 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 963224 T1722 oasc.ZkTestServer.run start zk server on port:49710
[junit4] 2> 963254 T1722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 963272 T1726 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6b40aa9d name:ZooKeeperConnection Watcher:127.0.0.1:49710 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 963273 T1722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 963274 T1722 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 963301 T1722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 963307 T1731 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7738516a name:ZooKeeperConnection Watcher:127.0.0.1:49710/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 963307 T1722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 963308 T1722 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 963334 T1722 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 963360 T1722 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 963373 T1722 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 963415 T1722 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 963416 T1722 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 963427 T1722 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 963428 T1722 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 963445 T1722 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 963446 T1722 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 963453 T1722 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 963453 T1722 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 963461 T1722 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 963462 T1722 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 963471 T1722 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 963472 T1722 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 963481 T1722 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 963482 T1722 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 963489 T1722 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 963490 T1722 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 963498 T1722 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 963499 T1722 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 963506 T1722 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 963507 T1722 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 963936 T1722 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 963946 T1722 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49713
[junit4] 2> 963947 T1722 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 963947 T1722 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 963947 T1722 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljetty-1382648096225
[junit4] 2> 963948 T1722 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljetty-1382648096225/'
[junit4] 2> 963985 T1722 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljetty-1382648096225/solr.xml
[junit4] 2> 964088 T1722 oasc.CoreContainer.<init> New CoreContainer 278805571
[junit4] 2> 964089 T1722 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljetty-1382648096225/]
[junit4] 2> 964091 T1722 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 964092 T1722 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 964093 T1722 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 964094 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 964095 T1722 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 964095 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 964095 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 964096 T1722 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 964096 T1722 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 964102 T1722 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 964103 T1722 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 964103 T1722 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49710/solr
[junit4] 2> 964105 T1722 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 964106 T1722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 964111 T1742 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@75c73ba1 name:ZooKeeperConnection Watcher:127.0.0.1:49710 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 964112 T1722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 964119 T1722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 964123 T1744 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6e4993b9 name:ZooKeeperConnection Watcher:127.0.0.1:49710/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 964124 T1722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 964128 T1722 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 964137 T1722 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 964147 T1722 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 964152 T1722 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49713_
[junit4] 2> 964154 T1722 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49713_
[junit4] 2> 964163 T1722 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 964176 T1722 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 964182 T1722 oasc.Overseer.start Overseer (id=90613225615720451-127.0.0.1:49713_-n_0000000000) starting
[junit4] 2> 964190 T1722 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 964200 T1746 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 964202 T1722 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 964210 T1722 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 964216 T1722 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 964227 T1745 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 964236 T1747 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 964237 T1747 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 964240 T1747 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 965745 T1745 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 965750 T1745 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:49713",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:49713_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 965751 T1745 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 965751 T1745 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 965782 T1744 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 966243 T1747 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 966244 T1747 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljetty-1382648096225/collection1
[junit4] 2> 966244 T1747 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 966246 T1747 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 966246 T1747 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 966249 T1747 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljetty-1382648096225/collection1/'
[junit4] 2> 966251 T1747 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljetty-1382648096225/collection1/lib/classes/' to classloader
[junit4] 2> 966252 T1747 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljetty-1382648096225/collection1/lib/README' to classloader
[junit4] 2> 966348 T1747 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 966434 T1747 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 966437 T1747 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 966452 T1747 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 967344 T1747 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 967358 T1747 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 967364 T1747 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 967398 T1747 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 967407 T1747 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 967417 T1747 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 967421 T1747 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 967422 T1747 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 967423 T1747 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 967428 T1747 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 967428 T1747 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 967429 T1747 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 967479 T1747 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-controljetty-1382648096225/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/control/data/
[junit4] 2> 967480 T1747 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2efd4c21
[junit4] 2> 967481 T1747 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/control/data
[junit4] 2> 967482 T1747 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/control/data/index/
[junit4] 2> 967483 T1747 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 967484 T1747 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/control/data/index
[junit4] 2> 967485 T1747 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=3.435546875, floorSegmentMB=0.4609375, forceMergeDeletesPctAllowed=9.815211966830024, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 967490 T1747 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 967490 T1747 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 967496 T1747 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 967497 T1747 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 967498 T1747 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 967498 T1747 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 967499 T1747 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 967499 T1747 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 967500 T1747 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 967501 T1747 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 967501 T1747 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 967502 T1747 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 967503 T1747 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 967503 T1747 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 967503 T1747 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 967504 T1747 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 967505 T1747 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 967519 T1747 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 967526 T1747 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 967526 T1747 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 967527 T1747 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=18.2939453125, floorSegmentMB=0.3994140625, forceMergeDeletesPctAllowed=17.38864380435177, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8007521846032332
[junit4] 2> 967530 T1747 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 967530 T1747 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 967531 T1747 oass.SolrIndexSearcher.<init> Opening Searcher@2b4c0608 main
[junit4] 2> 967540 T1748 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2b4c0608 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 967542 T1747 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 967542 T1747 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49713 collection:control_collection shard:shard1
[junit4] 2> 967545 T1747 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 967565 T1747 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 967573 T1747 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 967573 T1747 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 967573 T1747 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49713/collection1/
[junit4] 2> 967574 T1747 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 967574 T1747 oasc.SyncStrategy.syncToMe http://127.0.0.1:49713/collection1/ has no replicas
[junit4] 2> 967575 T1747 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49713/collection1/ shard1
[junit4] 2> 967575 T1747 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 968809 T1745 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 968859 T1744 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 968901 T1747 oasc.ZkController.register We are http://127.0.0.1:49713/collection1/ and leader is http://127.0.0.1:49713/collection1/
[junit4] 2> 968902 T1747 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49713
[junit4] 2> 968903 T1747 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 968904 T1747 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 968905 T1747 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 968914 T1747 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 968920 T1722 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 968922 T1722 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 968931 T1722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 968942 T1751 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5ab899d4 name:ZooKeeperConnection Watcher:127.0.0.1:49710/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 968943 T1722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 968950 T1722 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 968968 T1722 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4] 2> 969399 T1722 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 969404 T1722 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49717
[junit4] 2> 969405 T1722 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 969406 T1722 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 969406 T1722 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty1-1382648101675
[junit4] 2> 969407 T1722 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty1-1382648101675/'
[junit4] 2> 969449 T1722 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty1-1382648101675/solr.xml
[junit4] 2> 969567 T1722 oasc.CoreContainer.<init> New CoreContainer 766412566
[junit4] 2> 969568 T1722 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty1-1382648101675/]
[junit4] 2> 969570 T1722 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 969570 T1722 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 969571 T1722 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 969571 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 969572 T1722 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 969572 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 969573 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 969573 T1722 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 969573 T1722 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 969580 T1722 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 969580 T1722 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 969581 T1722 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49710/solr
[junit4] 2> 969581 T1722 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 969582 T1722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 969586 T1762 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6ef1bc2b name:ZooKeeperConnection Watcher:127.0.0.1:49710 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 969587 T1722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 969593 T1722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 969596 T1764 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@72d1e1c6 name:ZooKeeperConnection Watcher:127.0.0.1:49710/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 969597 T1722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 969607 T1722 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 970399 T1745 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 970402 T1745 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:49713",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:49713_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 970423 T1744 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 970424 T1751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 970425 T1764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 970618 T1722 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49717_
[junit4] 2> 970625 T1722 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49717_
[junit4] 2> 970643 T1751 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 970645 T1744 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 970646 T1764 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 970683 T1765 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 970684 T1765 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 970689 T1765 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 971960 T1745 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 971962 T1745 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:49717",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:49717_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 971962 T1745 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3]
[junit4] 2> 971963 T1745 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 971975 T1751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 971975 T1744 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 971975 T1764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 972698 T1765 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 972699 T1765 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty1-1382648101675/collection1
[junit4] 2> 972700 T1765 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 972703 T1765 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 972704 T1765 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 972710 T1765 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty1-1382648101675/collection1/'
[junit4] 2> 972715 T1765 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty1-1382648101675/collection1/lib/classes/' to classloader
[junit4] 2> 972717 T1765 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty1-1382648101675/collection1/lib/README' to classloader
[junit4] 2> 972852 T1765 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 972942 T1765 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 972946 T1765 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 972959 T1765 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 973833 T1765 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 973847 T1765 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 973853 T1765 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 973895 T1765 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 973904 T1765 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 973911 T1765 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 973914 T1765 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 973915 T1765 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 973916 T1765 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 973919 T1765 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 973919 T1765 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 973920 T1765 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 973921 T1765 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty1-1382648101675/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty1/
[junit4] 2> 973921 T1765 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2efd4c21
[junit4] 2> 973922 T1765 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty1
[junit4] 2> 973923 T1765 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty1/index/
[junit4] 2> 973924 T1765 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 973925 T1765 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty1/index
[junit4] 2> 973926 T1765 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=3.435546875, floorSegmentMB=0.4609375, forceMergeDeletesPctAllowed=9.815211966830024, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 973930 T1765 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 973930 T1765 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 973938 T1765 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 973939 T1765 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 973940 T1765 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 973940 T1765 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 973941 T1765 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 973941 T1765 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 973942 T1765 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 973942 T1765 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 973943 T1765 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 973944 T1765 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 973945 T1765 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 973945 T1765 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 973946 T1765 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 973947 T1765 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 973947 T1765 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 973963 T1765 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 973968 T1765 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 973969 T1765 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 973970 T1765 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=18.2939453125, floorSegmentMB=0.3994140625, forceMergeDeletesPctAllowed=17.38864380435177, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8007521846032332
[junit4] 2> 973972 T1765 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 973973 T1765 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 973973 T1765 oass.SolrIndexSearcher.<init> Opening Searcher@7bde4126 main
[junit4] 2> 973984 T1766 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7bde4126 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 973988 T1765 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 973988 T1765 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49717 collection:collection1 shard:shard1
[junit4] 2> 973991 T1765 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 974012 T1765 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 974019 T1765 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 974020 T1765 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 974020 T1765 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49717/collection1/
[junit4] 2> 974020 T1765 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 974020 T1765 oasc.SyncStrategy.syncToMe http://127.0.0.1:49717/collection1/ has no replicas
[junit4] 2> 974021 T1765 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49717/collection1/ shard1
[junit4] 2> 974021 T1765 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 975001 T1745 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 975020 T1744 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 975021 T1764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 975020 T1751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 975076 T1765 oasc.ZkController.register We are http://127.0.0.1:49717/collection1/ and leader is http://127.0.0.1:49717/collection1/
[junit4] 2> 975077 T1765 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49717
[junit4] 2> 975077 T1765 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 975078 T1765 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 975079 T1765 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 975088 T1765 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 975094 T1722 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 975095 T1722 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 975528 T1722 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 975533 T1722 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49720
[junit4] 2> 975535 T1722 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 975535 T1722 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 975536 T1722 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty2-1382648107804
[junit4] 2> 975536 T1722 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty2-1382648107804/'
[junit4] 2> 975577 T1722 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty2-1382648107804/solr.xml
[junit4] 2> 975682 T1722 oasc.CoreContainer.<init> New CoreContainer 4161001
[junit4] 2> 975683 T1722 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty2-1382648107804/]
[junit4] 2> 975685 T1722 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 975686 T1722 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 975686 T1722 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 975687 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 975687 T1722 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 975688 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 975688 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 975689 T1722 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 975689 T1722 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 975694 T1722 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 975695 T1722 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 975695 T1722 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49710/solr
[junit4] 2> 975696 T1722 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 975698 T1722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 975702 T1778 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4029400b name:ZooKeeperConnection Watcher:127.0.0.1:49710 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 975703 T1722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 975710 T1722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 975715 T1780 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@755c62e0 name:ZooKeeperConnection Watcher:127.0.0.1:49710/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 975715 T1722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 975727 T1722 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 976550 T1745 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 976555 T1745 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:49717",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:49717_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 976582 T1764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 976583 T1744 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 976585 T1751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 976583 T1780 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 976739 T1722 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49720_
[junit4] 2> 976745 T1722 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49720_
[junit4] 2> 976767 T1764 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 976768 T1744 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 976767 T1751 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 976768 T1780 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 976789 T1781 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 976789 T1781 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 976792 T1781 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 978108 T1745 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 978110 T1745 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:49720",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:49720_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 978110 T1745 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=3
[junit4] 2> 978110 T1745 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 978121 T1780 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 978122 T1751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 978122 T1764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 978122 T1744 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 978800 T1781 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 978801 T1781 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty2-1382648107804/collection1
[junit4] 2> 978802 T1781 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 978806 T1781 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 978807 T1781 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 978814 T1781 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty2-1382648107804/collection1/'
[junit4] 2> 978819 T1781 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty2-1382648107804/collection1/lib/classes/' to classloader
[junit4] 2> 978820 T1781 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty2-1382648107804/collection1/lib/README' to classloader
[junit4] 2> 978935 T1781 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 979021 T1781 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 979025 T1781 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 979040 T1781 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 979986 T1781 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 980001 T1781 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 980007 T1781 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 980042 T1781 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 980050 T1781 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 980059 T1781 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 980062 T1781 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 980063 T1781 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 980064 T1781 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 980066 T1781 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 980067 T1781 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 980067 T1781 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 980068 T1781 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty2-1382648107804/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty2/
[junit4] 2> 980068 T1781 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2efd4c21
[junit4] 2> 980072 T1781 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty2
[junit4] 2> 980073 T1781 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty2/index/
[junit4] 2> 980074 T1781 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 980075 T1781 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty2/index
[junit4] 2> 980076 T1781 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=3.435546875, floorSegmentMB=0.4609375, forceMergeDeletesPctAllowed=9.815211966830024, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 980081 T1781 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 980081 T1781 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 980090 T1781 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 980091 T1781 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 980091 T1781 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 980092 T1781 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 980092 T1781 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 980093 T1781 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 980093 T1781 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 980094 T1781 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 980094 T1781 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 980095 T1781 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 980096 T1781 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 980097 T1781 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 980097 T1781 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 980098 T1781 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 980099 T1781 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 980113 T1781 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 980119 T1781 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 980119 T1781 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 980121 T1781 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=18.2939453125, floorSegmentMB=0.3994140625, forceMergeDeletesPctAllowed=17.38864380435177, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8007521846032332
[junit4] 2> 980123 T1781 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 980124 T1781 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 980124 T1781 oass.SolrIndexSearcher.<init> Opening Searcher@456277f5 main
[junit4] 2> 980478 T1782 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@456277f5 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 980483 T1781 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 980483 T1781 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49720 collection:collection1 shard:shard2
[junit4] 2> 980486 T1781 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 980506 T1781 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 980513 T1781 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 980514 T1781 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 980514 T1781 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49720/collection1/
[junit4] 2> 980514 T1781 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 980515 T1781 oasc.SyncStrategy.syncToMe http://127.0.0.1:49720/collection1/ has no replicas
[junit4] 2> 980515 T1781 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49720/collection1/ shard2
[junit4] 2> 980515 T1781 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 981143 T1745 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 981173 T1780 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 981173 T1744 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 981173 T1751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 981174 T1764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 981204 T1781 oasc.ZkController.register We are http://127.0.0.1:49720/collection1/ and leader is http://127.0.0.1:49720/collection1/
[junit4] 2> 981204 T1781 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49720
[junit4] 2> 981205 T1781 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 981205 T1781 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 981205 T1781 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 981211 T1781 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 981214 T1722 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 981215 T1722 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 981775 T1722 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 981780 T1722 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49723
[junit4] 2> 981782 T1722 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 981783 T1722 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 981783 T1722 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-1382648113923
[junit4] 2> 981784 T1722 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-1382648113923/'
[junit4] 2> 981829 T1722 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-1382648113923/solr.xml
[junit4] 2> 981963 T1722 oasc.CoreContainer.<init> New CoreContainer 586100091
[junit4] 2> 981964 T1722 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-1382648113923/]
[junit4] 2> 981967 T1722 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 981967 T1722 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 981968 T1722 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 981968 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 981969 T1722 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 981969 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 981970 T1722 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 981970 T1722 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 981971 T1722 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 981976 T1722 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 981977 T1722 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 981977 T1722 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49710/solr
[junit4] 2> 981978 T1722 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 981980 T1722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 981984 T1794 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c9eb47f name:ZooKeeperConnection Watcher:127.0.0.1:49710 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 981985 T1722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 981991 T1722 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 981998 T1796 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1511c6ee name:ZooKeeperConnection Watcher:127.0.0.1:49710/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 981999 T1722 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 982012 T1722 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 982695 T1745 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 982697 T1745 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:49720",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:49720_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 982707 T1780 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 982708 T1751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 982708 T1764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 982707 T1796 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 982708 T1744 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 983022 T1722 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49723_
[junit4] 2> 983025 T1722 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49723_
[junit4] 2> 983033 T1780 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 983034 T1764 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 983034 T1796 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 983034 T1751 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 983036 T1744 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 983084 T1797 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 983085 T1797 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 983087 T1797 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 984228 T1745 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 984230 T1745 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=3 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:49723",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:49723_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"3",
[junit4] 2> "core_node_name":"core_node3"}
[junit4] 2> 984231 T1745 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=3
[junit4] 2> 984231 T1745 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard3
[junit4] 2> 984247 T1780 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 984247 T1796 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 984247 T1751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 984248 T1764 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 984247 T1744 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 985090 T1797 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 985091 T1797 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-1382648113923/collection1
[junit4] 2> 985091 T1797 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 985093 T1797 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 985093 T1797 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 985096 T1797 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-1382648113923/collection1/'
[junit4] 2> 985097 T1797 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-1382648113923/collection1/lib/classes/' to classloader
[junit4] 2> 985098 T1797 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-1382648113923/collection1/lib/README' to classloader
[junit4] 2> 985193 T1797 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 985278 T1797 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 985281 T1797 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 985296 T1797 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 986256 T1797 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 986269 T1797 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 986274 T1797 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 986308 T1797 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 986315 T1797 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 986323 T1797 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 986326 T1797 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 986327 T1797 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 986327 T1797 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 986330 T1797 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 986330 T1797 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 986331 T1797 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 986331 T1797 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-jetty3-1382648113923/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty3/
[junit4] 2> 986331 T1797 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2efd4c21
[junit4] 2> 986333 T1797 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty3
[junit4] 2> 986333 T1797 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty3/index/
[junit4] 2> 986334 T1797 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty3/index' doesn't exist. Creating new index...
[junit4] 2> 986334 T1797 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty3/index
[junit4] 2> 986335 T1797 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=3.435546875, floorSegmentMB=0.4609375, forceMergeDeletesPctAllowed=9.815211966830024, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 986341 T1797 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty3/index,segFN=segments_1,generation=1}
[junit4] 2> 986341 T1797 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 986347 T1797 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 986347 T1797 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 986347 T1797 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 986348 T1797 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 986348 T1797 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 986348 T1797 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 986349 T1797 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 986349 T1797 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 986349 T1797 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 986350 T1797 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 986351 T1797 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 986351 T1797 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 986352 T1797 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 986352 T1797 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 986353 T1797 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 986364 T1797 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 986368 T1797 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 986369 T1797 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 986370 T1797 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=18.2939453125, floorSegmentMB=0.3994140625, forceMergeDeletesPctAllowed=17.38864380435177, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8007521846032332
[junit4] 2> 986371 T1797 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty3/index,segFN=segments_1,generation=1}
[junit4] 2> 986372 T1797 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 986372 T1797 oass.SolrIndexSearcher.<init> Opening Searcher@34b4626b main
[junit4] 2> 986381 T1798 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@34b4626b main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 986383 T1797 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 986383 T1797 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49723 collection:collection1 shard:shard3
[junit4] 2> 986386 T1797 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
[junit4] 2> 986402 T1797 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3
[junit4] 2> 986409 T1797 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 986409 T1797 oasc.ShardLeaderElectionContext.runLead
[...truncated too long message...]
tly being tracked
[junit4] 2> 1157788 T1722 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty12/index.20131024155606181 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty12/index.20131024155606181;done=false>>]
[junit4] 2> 1157788 T1722 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty12/index.20131024155606181
[junit4] 2> 1157788 T1722 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty12 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty12;done=false>>]
[junit4] 2> 1157789 T1722 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ChaosMonkeySafeLeaderTest-1382648095911/jetty12
[junit4] 2> 1157790 T2079 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90613225615720476-127.0.0.1:49771_-n_0000000012) am no longer a leader.
[junit4] 2> 1157793 T1955 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1157809 T1722 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 1163469 T1722 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> 1163473 T1722 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:49710 49710
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 1> safe stop:
[junit4] 1> safe stop:
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ChaosMonkeySafeLeaderTest -Dtests.method=testDistribSearch -Dtests.seed=6C5B384E6F801A63 -Dtests.slow=true -Dtests.locale=fr_CH -Dtests.timezone=SystemV/EST5 -Dtests.file.encoding=UTF-8
[junit4] ERROR 200s | ChaosMonkeySafeLeaderTest.testDistribSearch <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:49723/collection1, http://127.0.0.1:49756/collection1, http://127.0.0.1:49771/collection1, http://127.0.0.1:49761/collection1, http://127.0.0.1:49720/collection1, http://127.0.0.1:49717/collection1, http://127.0.0.1:49730/collection1, http://127.0.0.1:49744/collection1, http://127.0.0.1:49766/collection1, http://127.0.0.1:49751/collection1, http://127.0.0.1:49726/collection1, http://127.0.0.1:49737/collection1]
[junit4] > at __randomizedtesting.SeedInfo.seed([6C5B384E6F801A63:EDBDB65618DF7A5F]:0)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:337)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:640)
[junit4] > at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
[junit4] > at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1158)
[junit4] > at org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.doTest(ChaosMonkeySafeLeaderTest.java:135)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:49730/collection1
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:476)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:268)
[junit4] > ... 46 more
[junit4] > Caused by: java.net.SocketException: Bad file descriptor
[junit4] > at java.net.SocketOutputStream.socketWrite0(Native Method)
[junit4] > at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:113)
[junit4] > at java.net.SocketOutputStream.write(SocketOutputStream.java:159)
[junit4] > at org.apache.http.impl.io.AbstractSessionOutputBuffer.flushBuffer(AbstractSessionOutputBuffer.java:147)
[junit4] > at org.apache.http.impl.io.AbstractSessionOutputBuffer.flush(AbstractSessionOutputBuffer.java:154)
[junit4] > at org.apache.http.impl.AbstractHttpClientConnection.doFlush(AbstractHttpClientConnection.java:278)
[junit4] > at org.apache.http.impl.AbstractHttpClientConnection.flush(AbstractHttpClientConnection.java:283)
[junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.flush(ManagedClientConnectionImpl.java:175)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:260)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:715)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:520)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:366)
[junit4] > ... 48 more
[junit4] 2> 1163614 T1722 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 200462 T1721 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene46: {range_facet_l=PostingsFormat(name=NestedPulsing), text=PostingsFormat(name=Lucene41VarGapFixedInterval), _version_=PostingsFormat(name=NestedPulsing), rnd_b=PostingsFormat(name=Memory doPackFST= false), intDefault=PostingsFormat(name=NestedPulsing), timestamp=PostingsFormat(name=NestedPulsing), id=PostingsFormat(name=Memory doPackFST= false), a_t=PostingsFormat(name=NestedPulsing), range_facet_sl=PostingsFormat(name=Lucene41VarGapFixedInterval), range_facet_si=FST41, other_tl1=PostingsFormat(name=NestedPulsing), multiDefault=FST41, a_si=FST41}, docValues:{timestamp=DocValuesFormat(name=SimpleText)}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=fr_CH, timezone=SystemV/EST5
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_45 (64-bit)/cpus=2,threads=1,free=235953936,total=533725184
[junit4] 2> NOTE: All tests run in this JVM: [MoreLikeThisHandlerTest, TestStressVersions, HighlighterConfigTest, ShardSplitTest, TestComponentsName, TestShardHandlerFactory, TestCodecSupport, UnloadDistributedZkTest, XmlUpdateRequestHandlerTest, SolrIndexSplitterTest, SolrXmlInZkTest, SliceStateUpdateTest, MBeansHandlerTest, TestFieldTypeCollectionResource, ScriptEngineTest, NumericFieldsTest, SpellPossibilityIteratorTest, UpdateParamsTest, HdfsChaosMonkeySafeLeaderTest, SampleTest, DocValuesTest, CoreAdminCreateDiscoverTest, BadCopyFieldTest, HdfsLockFactoryTest, TestSchemaSimilarityResource, TestQueryTypes, TestRandomFaceting, TestNoOpRegenerator, CacheHeaderTest, SOLR749Test, TestSolrXmlPersistence, TestBM25SimilarityFactory, OpenExchangeRatesOrgProviderTest, TestSolrIndexConfig, TestManagedSchemaFieldResource, StatsComponentTest, LeaderElectionTest, TestFaceting, RequestHandlersTest, TestReload, UniqFieldsUpdateProcessorFactoryTest, TestSchemaNameResource, TestCSVLoader, TestRandomMergePolicy, TestSolrXmlPersistor, UpdateRequestProcessorFactoryTest, ZkCLITest, PathHierarchyTokenizerFactoryTest, BasicDistributedZkTest, InfoHandlerTest, AssignTest, TestHashPartitioner, TestDFRSimilarityFactory, ZkSolrClientTest, LeaderElectionIntegrationTest, StatelessScriptUpdateProcessorFactoryTest, TestSolrXMLSerializer, ChaosMonkeySafeLeaderTest]
[junit4] Completed in 201.29s, 1 test, 1 error <<< FAILURES!
[...truncated 827 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:422: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:402: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:483: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1259: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:902: There were test failures: 327 suites, 1451 tests, 1 error, 40 ignored (5 assumptions)
Total time: 109 minutes 3 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.7.0) - Build # 946 -
Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/946/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
All tests passed
Build Log:
[...truncated 9765 lines...]
[junit4] JVM J0: stdout was not empty, see: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20131025_122106_052.sysout
[junit4] >>> JVM J0: stdout (verbatim) ----
[junit4] #
[junit4] # A fatal error has been detected by the Java Runtime Environment:
[junit4] #
[junit4] # SIGSEGV (0xb) at pc=0x000000010feeba2b, pid=185, tid=134147
[junit4] #
[junit4] # JRE version: Java(TM) SE Runtime Environment (7.0_45-b18) (build 1.7.0_45-b18)
[junit4] # Java VM: Java HotSpot(TM) 64-Bit Server VM (24.45-b08 mixed mode bsd-amd64 )
[junit4] # Problematic frame:
[junit4] # C [libjava.dylib+0x9a2b] JNU_NewStringPlatform+0x1d3
[junit4] #
[junit4] # Failed to write core dump. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
[junit4] #
[junit4] # An error report file with more information is saved as:
[junit4] # /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/hs_err_pid185.log
[junit4] [thread 139779 also had an error]
[junit4] #
[junit4] # If you would like to submit a bug report, please visit:
[junit4] # http://bugreport.sun.com/bugreport/crash.jsp
[junit4] # The crash happened outside the Java Virtual Machine in native code.
[junit4] # See problematic frame for where to report the bug.
[junit4] #
[junit4] <<< JVM J0: EOF ----
[...truncated 1 lines...]
[junit4] ERROR: JVM J0 ended with an exception, command line: /Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/bin/java -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/heapdumps -Dtests.prefix=tests -Dtests.seed=F31293EB573940CD -Xmx512M -Dtests.iters= -Dtests.verbose=false -Dtests.infostream=false -Dtests.codec=random -Dtests.postingsformat=random -Dtests.docvaluesformat=random -Dtests.locale=random -Dtests.timezone=random -Dtests.directory=random -Dtests.linedocsfile=europarl.lines.txt.gz -Dtests.luceneMatchVersion=5.0 -Dtests.cleanthreads=perClass -Djava.util.logging.config.file=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/tools/junit4/logging.properties -Dtests.nightly=false -Dtests.weekly=false -Dtests.slow=true -Dtests.asserts.gracious=false -Dtests.multiplier=1 -DtempDir=. -Djava.io.tmpdir=. -Djunit4.tempDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/temp -Dclover.db.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/clover/db -Djava.security.manager=org.apache.lucene.util.TestSecurityManager -Djava.security.policy=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/tools/junit4/tests.policy -Dlucene.version=5.0-SNAPSHOT -Djetty.testMode=1 -Djetty.insecurerandom=1 -Dsolr.directoryFactory=org.apache.solr.core.MockDirectoryFactory -Djava.awt.headless=true -Dtests.disableHdfs=true -Dfile.encoding=UTF-8 -classpath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/classes/test:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-test-framework/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/test-framework/lib/junit4-ant-2.0.10.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/test-framework/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/codecs/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/codecs/lucene-codecs-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/memory/lucene-memory-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/spatial/lucene-spatial-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/queries/lucene-queries-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/join/lucene-join-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/commons-cli-1.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/commons-codec-1.7.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/commons-configuration-1.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/commons-fileupload-1.2.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/commons-lang-2.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/concurrentlinkedhashmap-lru-1.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/dom4j-1.6.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/guava-14.0.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/hadoop-annotations-2.2.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/hadoop-auth-2.2.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/hadoop-common-2.2.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/hadoop-hdfs-2.2.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/hppc-0.5.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/joda-time-2.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/org.restlet-2.1.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/org.restlet.ext.servlet-2.1.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/protobuf-java-2.5.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/spatial4j-0.3.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/commons-io-2.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/httpclient-4.2.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/httpcore-4.2.5.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/httpmime-4.2.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/jcl-over-slf4j-1.6.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/jul-to-slf4j-1.6.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/log4j-1.2.16.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/noggit-0.5.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/slf4j-api-1.6.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/slf4j-log4j12-1.6.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/wstx-asl-3.2.7.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/zookeeper-3.4.5.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-continuation-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-deploy-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-http-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-io-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-jmx-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-security-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-server-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-servlet-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-util-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-webapp-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-xml-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/servlet-api-3.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/core/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/test-framework/lib/junit-4.10.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/test-framework/lib/randomizedtesting-runner-2.0.10.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/cglib-nodep-2.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/commons-collections-3.2.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/dom4j-1.6.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/easymock-3.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/hadoop-common-2.2.0-tests.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/hadoop-hdfs-2.2.0-tests.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/hppc-0.5.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/javax.servlet-api-3.0.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/jersey-core-1.16.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/jetty-6.1.26.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/jetty-util-6.1.26.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/objenesis-1.2.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/Users/jenkins/.ant/lib/ivy-2.3.0.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/lib/tools.jar:/Users/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.10.jar -ea:org.apache.lucene... -ea:org.apache.solr... com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -flush -eventsfile /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20131025_122106_052.events @/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20131025_122106_052.suites
[junit4] ERROR: JVM J0 ended with an exception: Forked process returned with error code: 134. Very likely a JVM crash. Process output piped in logs above.
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1254)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:67)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:822)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:819)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] at java.lang.Thread.run(Thread.java:744)
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:422: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:402: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:483: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1259: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:902: At least one slave process threw an exception, first: Forked process returned with error code: 134. Very likely a JVM crash. Process output piped in logs above.
Total time: 41 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.7.0) - Build # 945 -
Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/945/
Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.cloud.ShardSplitTest.testDistribSearch
Error Message:
Wrong doc count on shard1_0 expected:<130> but was:<82>
Stack Trace:
java.lang.AssertionError: Wrong doc count on shard1_0 expected:<130> but was:<82>
at __randomizedtesting.SeedInfo.seed([26991E9917156F3:838F1FF1E62E36CF]: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.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:477)
at org.apache.solr.cloud.ShardSplitTest.splitByUniqueKeyTest(ShardSplitTest.java:249)
at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:113)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.GeneratedMethodAccessor58.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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:744)
Build Log:
[...truncated 18720 lines...]
[junit4] Suite: org.apache.solr.cloud.ShardSplitTest
[junit4] 2> 2694247 T7485 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /uhp/l
[junit4] 2> 2694255 T7485 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ShardSplitTest-1382682116560
[junit4] 2> 2694257 T7485 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 2694259 T7486 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 2694360 T7485 oasc.ZkTestServer.run start zk server on port:51436
[junit4] 2> 2694361 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2694369 T7492 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@45d2fc5a name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694369 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2694370 T7485 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2694379 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2694384 T7494 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@285bf4e name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694384 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2694385 T7485 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 2694393 T7485 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 2694399 T7485 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 2694406 T7485 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 2694413 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2694414 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2694425 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 2694425 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 2694432 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2694433 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2694440 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2694441 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2694448 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2694449 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 2694455 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2694458 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 2694467 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2694468 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2694480 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2694480 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2694492 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2694493 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2694507 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2694508 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2694876 T7485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2694886 T7485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51439
[junit4] 2> 2694886 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2694887 T7485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2694887 T7485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822
[junit4] 2> 2694887 T7485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/'
[junit4] 2> 2694927 T7485 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/solr.xml
[junit4] 2> 2695018 T7485 oasc.CoreContainer.<init> New CoreContainer 311952099
[junit4] 2> 2695019 T7485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/]
[junit4] 2> 2695020 T7485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2695020 T7485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2695021 T7485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2695021 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2695022 T7485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2695022 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2695022 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2695023 T7485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2695023 T7485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2695028 T7485 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2695029 T7485 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2695029 T7485 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51436/solr
[junit4] 2> 2695029 T7485 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2695031 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2695041 T7505 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@231ca705 name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2695041 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2695046 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2695050 T7507 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@753ff1ab name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2695051 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2695057 T7485 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 2695068 T7485 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 2695077 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2695082 T7485 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51439_uhp%2Fl
[junit4] 2> 2695084 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51439_uhp%2Fl
[junit4] 2> 2695094 T7485 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 2695105 T7485 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 2695114 T7485 oasc.Overseer.start Overseer (id=90615455191400451-127.0.0.1:51439_uhp%2Fl-n_0000000000) starting
[junit4] 2> 2695129 T7485 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 2695144 T7509 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 2695145 T7485 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 2695152 T7485 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 2695163 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2695178 T7508 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 2695194 T7510 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2695194 T7510 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2695200 T7510 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2696698 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2696699 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51439/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51439_uhp%2Fl",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2696700 T7508 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 2696700 T7508 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2696713 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2697206 T7510 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2697206 T7510 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/collection1
[junit4] 2> 2697206 T7510 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 2697208 T7510 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2697208 T7510 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 2697211 T7510 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/collection1/'
[junit4] 2> 2697213 T7510 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/collection1/lib/classes/' to classloader
[junit4] 2> 2697214 T7510 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/collection1/lib/README' to classloader
[junit4] 2> 2697299 T7510 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2697377 T7510 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2697381 T7510 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2697393 T7510 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2698179 T7510 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2698183 T7510 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2698189 T7510 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2698201 T7510 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2698273 T7510 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2698274 T7510 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/collection1/, dataDir=./org.apache.solr.cloud.ShardSplitTest-1382682116559/control/data/
[junit4] 2> 2698274 T7510 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d4d87e
[junit4] 2> 2698274 T7510 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/control/data
[junit4] 2> 2698275 T7510 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ShardSplitTest-1382682116559/control/data/index/
[junit4] 2> 2698275 T7510 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ShardSplitTest-1382682116559/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 2698275 T7510 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/control/data/index
[junit4] 2> 2698276 T7510 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=42.181640625, floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=28.419714701414733, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2698276 T7510 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@60a87148 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e656e56),segFN=segments_1,generation=1}
[junit4] 2> 2698276 T7510 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2698279 T7510 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2698280 T7510 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2698280 T7510 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2698280 T7510 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2698281 T7510 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2698281 T7510 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2698282 T7510 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2698283 T7510 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2698283 T7510 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2698284 T7510 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2698285 T7510 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2698285 T7510 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2698285 T7510 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2698286 T7510 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2698287 T7510 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2698298 T7510 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2698304 T7510 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2698305 T7510 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2698306 T7510 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10609207416541731]
[junit4] 2> 2698306 T7510 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@60a87148 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e656e56),segFN=segments_1,generation=1}
[junit4] 2> 2698306 T7510 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2698307 T7510 oass.SolrIndexSearcher.<init> Opening Searcher@23223163 main
[junit4] 2> 2698312 T7511 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@23223163 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2698312 T7510 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2698313 T7510 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51439/uhp/l collection:control_collection shard:shard1
[junit4] 2> 2698316 T7510 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 2698343 T7510 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2698350 T7510 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2698350 T7510 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 2698351 T7510 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51439/uhp/l/collection1/
[junit4] 2> 2698351 T7510 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2698351 T7510 oasc.SyncStrategy.syncToMe http://127.0.0.1:51439/uhp/l/collection1/ has no replicas
[junit4] 2> 2698352 T7510 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51439/uhp/l/collection1/ shard1
[junit4] 2> 2698352 T7510 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 2699731 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2699759 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2699790 T7510 oasc.ZkController.register We are http://127.0.0.1:51439/uhp/l/collection1/ and leader is http://127.0.0.1:51439/uhp/l/collection1/
[junit4] 2> 2699790 T7510 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51439/uhp/l
[junit4] 2> 2699790 T7510 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2699791 T7510 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2699791 T7510 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2699795 T7510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2699798 T7485 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2699799 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2699801 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2699806 T7514 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2fd45c5a name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2699806 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2699810 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2699818 T7485 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4] 2> 2700170 T7485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2700175 T7485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51443
[junit4] 2> 2700178 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2700178 T7485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2700179 T7485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121
[junit4] 2> 2700179 T7485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/'
[junit4] 2> 2700223 T7485 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/solr.xml
[junit4] 2> 2700319 T7485 oasc.CoreContainer.<init> New CoreContainer 2132033048
[junit4] 2> 2700320 T7485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/]
[junit4] 2> 2700322 T7485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2700322 T7485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2700322 T7485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2700323 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2700323 T7485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2700324 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2700324 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2700324 T7485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2700325 T7485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2700330 T7485 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2700331 T7485 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2700332 T7485 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51436/solr
[junit4] 2> 2700332 T7485 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2700334 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2700340 T7525 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@150e4b76 name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2700340 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2700346 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2700350 T7527 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@53c0cfab name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2700351 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2700370 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2701282 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2701289 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:51439/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51439_uhp%2Fl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2701302 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2701302 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2701302 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2701384 T7485 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51443_uhp%2Fl
[junit4] 2> 2701387 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51443_uhp%2Fl
[junit4] 2> 2701393 T7527 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2701394 T7514 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2701395 T7507 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2701420 T7528 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2701421 T7528 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2701424 T7528 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2702815 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2702817 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51443/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51443_uhp%2Fl",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2702818 T7508 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 2702818 T7508 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2702830 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2702830 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2702830 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2703426 T7528 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2703426 T7528 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/collection1
[junit4] 2> 2703426 T7528 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2703428 T7528 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2703428 T7528 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2703432 T7528 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/collection1/'
[junit4] 2> 2703434 T7528 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/collection1/lib/classes/' to classloader
[junit4] 2> 2703435 T7528 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/collection1/lib/README' to classloader
[junit4] 2> 2703521 T7528 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2703579 T7528 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2703585 T7528 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2703598 T7528 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2704304 T7528 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2704307 T7528 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2704309 T7528 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2704319 T7528 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2704391 T7528 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2704391 T7528 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/collection1/, dataDir=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty1/
[junit4] 2> 2704391 T7528 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d4d87e
[junit4] 2> 2704392 T7528 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty1
[junit4] 2> 2704392 T7528 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty1/index/
[junit4] 2> 2704393 T7528 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 2704393 T7528 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty1/index
[junit4] 2> 2704393 T7528 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=42.181640625, floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=28.419714701414733, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2704394 T7528 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@569bfe84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b08b4c1),segFN=segments_1,generation=1}
[junit4] 2> 2704394 T7528 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2704399 T7528 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2704400 T7528 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2704401 T7528 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2704401 T7528 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2704402 T7528 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2704402 T7528 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2704402 T7528 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2704403 T7528 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2704403 T7528 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2704403 T7528 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2704404 T7528 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2704404 T7528 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2704404 T7528 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2704405 T7528 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2704405 T7528 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2704418 T7528 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2704424 T7528 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2704426 T7528 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2704426 T7528 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10609207416541731]
[junit4] 2> 2704427 T7528 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@569bfe84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b08b4c1),segFN=segments_1,generation=1}
[junit4] 2> 2704427 T7528 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2704427 T7528 oass.SolrIndexSearcher.<init> Opening Searcher@4047f15d main
[junit4] 2> 2704433 T7529 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4047f15d main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2704435 T7528 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2704435 T7528 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51443/uhp/l collection:collection1 shard:shard1
[junit4] 2> 2704438 T7528 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 2704467 T7528 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2704475 T7528 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2704476 T7528 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 2704476 T7528 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51443/uhp/l/collection1/
[junit4] 2> 2704476 T7528 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2704477 T7528 oasc.SyncStrategy.syncToMe http://127.0.0.1:51443/uhp/l/collection1/ has no replicas
[junit4] 2> 2704477 T7528 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51443/uhp/l/collection1/ shard1
[junit4] 2> 2704477 T7528 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 2705850 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2705874 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2705875 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2705874 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2705901 T7528 oasc.ZkController.register We are http://127.0.0.1:51443/uhp/l/collection1/ and leader is http://127.0.0.1:51443/uhp/l/collection1/
[junit4] 2> 2705902 T7528 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51443/uhp/l
[junit4] 2> 2705902 T7528 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2705903 T7528 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2705903 T7528 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2705906 T7528 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2705910 T7485 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2705910 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2706261 T7485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2706269 T7485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51446
[junit4] 2> 2706271 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2706271 T7485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2706272 T7485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215
[junit4] 2> 2706272 T7485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/'
[junit4] 2> 2706310 T7485 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/solr.xml
[junit4] 2> 2706399 T7485 oasc.CoreContainer.<init> New CoreContainer 1984235485
[junit4] 2> 2706399 T7485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/]
[junit4] 2> 2706401 T7485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2706402 T7485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2706402 T7485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2706402 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2706402 T7485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2706403 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2706403 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2706404 T7485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2706404 T7485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2706408 T7485 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2706408 T7485 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2706409 T7485 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51436/solr
[junit4] 2> 2706409 T7485 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2706411 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2706416 T7541 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a24a2a1 name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2706416 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2706423 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2706431 T7543 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@47de00aa name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2706433 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2706446 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2707394 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2707395 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:51443/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51443_uhp%2Fl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2707409 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2707410 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2707410 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2707410 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2707456 T7485 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51446_uhp%2Fl
[junit4] 2> 2707459 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51446_uhp%2Fl
[junit4] 2> 2707467 T7527 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2707468 T7507 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2707468 T7543 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2707468 T7514 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2707487 T7544 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2707487 T7544 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2707490 T7544 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2708926 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2708929 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51446/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51446_uhp%2Fl",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 2708929 T7508 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 2708930 T7508 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 2708941 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2708942 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2708942 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2708942 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2709492 T7544 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2709493 T7544 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/collection1
[junit4] 2> 2709493 T7544 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2709495 T7544 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2709495 T7544 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2709498 T7544 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/collection1/'
[junit4] 2> 2709499 T7544 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/collection1/lib/classes/' to classloader
[junit4] 2> 2709500 T7544 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/collection1/lib/README' to classloader
[junit4] 2> 2709577 T7544 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2709643 T7544 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2709648 T7544 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2709662 T7544 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2710390 T7544 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2710395 T7544 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2710397 T7544 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2710406 T7544 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2710504 T7544 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2710505 T7544 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/collection1/, dataDir=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty2/
[junit4] 2> 2710505 T7544 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d4d87e
[junit4] 2> 2710506 T7544 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty2
[junit4] 2> 2710506 T7544 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty2/index/
[junit4] 2> 2710506 T7544 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 2710507 T7544 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty2/index
[junit4] 2> 2710508 T7544 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=42.181640625, floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=28.419714701414733, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2710509 T7544 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6c2be92a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70a30453),segFN=segments_1,generation=1}
[junit4] 2> 2710509 T7544 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2710515 T7544 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2710516 T7544 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2710517 T7544 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2710517 T7544 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2710517 T7544 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2710518 T7544 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2710519 T7544 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2710519 T7544 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2710520 T7544 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2710521 T7544 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2710522 T7544 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2710522 T7544 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2710522 T7544 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2710523 T7544 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2710524 T7544 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2710534 T7544 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2710538 T7544 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2710539 T7544 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2710539 T7544 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10609207416541731]
[junit4] 2> 2710540 T7544 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6c2be92a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70a30453),segFN=segments_1,generation=1}
[junit4] 2> 2710540 T7544 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2710540 T7544 oass.SolrIndexSearcher.<init> Opening Searcher@217e56b5 main
[junit4] 2> 2710548 T7545 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@217e56b5 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2710552 T7544 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2710552 T7544 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51446/uhp/l collection:collection1 shard:shard2
[junit4] 2> 2710555 T7544 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 2710582 T7544 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 2710589 T7544 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2710589 T7544 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 2710590 T7544 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51446/uhp/l/collection1/
[junit4] 2> 2710590 T7544 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2710590 T7544 oasc.SyncStrategy.syncToMe http://127.0.0.1:51446/uhp/l/collection1/ has no replicas
[junit4] 2> 2710590 T7544 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51446/uhp/l/collection1/ shard2
[junit4] 2> 2710591 T7544 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 2711975 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2712002 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2712002 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2712002 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2712003 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2712050 T7544 oasc.ZkController.register We are http://127.0.0.1:51446/uhp/l/collection1/ and leader is http://127.0.0.1:51446/uhp/l/collection1/
[junit4] 2> 2712050 T7544 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51446/uhp/l
[junit4] 2> 2712051 T7544 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2712051 T7544 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2712051 T7544 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2712056 T7544 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2712059 T7485 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2712060 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2712413 T7485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2712418 T7485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51449
[junit4] 2> 2712420 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2712420 T7485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2712420 T7485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364
[junit4] 2> 2712420 T7485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/'
[junit4] 2> 2712450 T7485 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/solr.xml
[junit4] 2> 2712544 T7485 oasc.CoreContainer.<init> New CoreContainer 638187818
[junit4] 2> 2712544 T7485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/]
[junit4] 2> 2712546 T7485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2712546 T7485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2712546 T7485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2712547 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2712547 T7485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2712547 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2712547 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2712548 T7485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2712548 T7485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2712552 T7485 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2712553 T7485 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2712553 T7485 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51436/solr
[junit4] 2> 2712553 T7485 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2712555 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2712560 T7557 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6ba07d19 name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2712561 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2712567 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2712572 T7559 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8d72211 name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2712573 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2712583 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2713523 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2713525 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:51446/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51446_uhp%2Fl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 2713540 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2713540 T7559 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2713541 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2713541 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2713541 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2713598 T7485 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51449_uhp%2Fl
[junit4] 2> 2713602 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51449_uhp%2Fl
[junit4] 2> 2713608 T7559 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2713609 T7507 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2713610 T7543 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2713609 T7514 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2713610 T7527 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2713634 T7560 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2713634 T7560 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2713642 T7560 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2715054 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2715056 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51449/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51449_uhp%2Fl",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node3"}
[junit4] 2> 2715056 T7508 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 2715057 T7508 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2715069 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2715070 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2715070 T7559 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2715069 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2715069 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2715645 T7560 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2715646 T7560 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/collection1
[junit4] 2> 2715646 T7560 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2715648 T7560 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2715649 T7560 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2715652 T7560 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/collection1/'
[junit4] 2> 2715653 T7560 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/collection1/lib/classes/' to classloader
[junit4] 2> 2715654 T7560 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/collection1/lib/README' to classloader
[junit4] 2> 2715733 T7560 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2715803 T7560 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2715807 T7560 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2715821 T7560 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2716525 T7560 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2716529 T7560 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2716531 T7560 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2716539 T7560 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2716615 T7560 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2716615 T7560 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/collection1/, dataDir=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty3/
[junit4] 2> 2716616 T7560 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d4d87e
[junit4] 2> 2716617 T7560 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty3
[junit4] 2> 2716617 T7560 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty3/index/
[junit4] 2> 2716618 T7560 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty3/index' doesn't exist. Creating new index...
[junit4] 2> 2716618 T7560 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty3/index
[junit4] 2> 2716619 T7560 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=42.181640625, floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=28.419714701414733, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2716620 T7560 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3f7c7ce2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29f42af1),segFN=segments_1,generation=1}
[junit4] 2> 2716620 T7560 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2716627 T7560 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2716628 T7560 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2716628 T7560 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2716628 T7560 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2716629 T7560 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2716629 T7560 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2716630 T7560 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2716630 T7560 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2716630 T7560 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2716631 T7560 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2716632 T7560 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2716633 T7560 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2716633 T7560 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2716635 T7560 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2716636 T7560 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2716649 T7560 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2716655 T7560 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2716655 T7560 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2716657 T7560 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10609207416541731]
[junit4] 2> 2716658 T7560 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3f7c7ce2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29f42af1),segFN=segments_1,generation=1}
[junit4] 2> 2716658 T7560 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2716658 T7560 oass.SolrIndexSearcher.<init> Opening Searcher@dacb909 main
[junit4] 2> 2716663 T7561 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@dacb909 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2716663 T7560 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2716664 T7560 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51449/uhp/l collection:collection1 shard:shard1
[junit4] 2> 2716674 T7560 oasc.ZkController.register We are http://127.0.0.1:51449/uhp/l/collection1/ and leader is http://127.0.0.1:51443/uhp/l/collection1/
[junit4] 2> 2716674 T7560 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51449/uhp/l
[junit4] 2> 2716674 T7560 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 2716674 T7560 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C1386 name=collection1 org.apache.solr.core.SolrCore@ba5293 url=http://127.0.0.1:51449/uhp/l/collection1 node=127.0.0.1:51449_uhp%2Fl C1386_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:51449/uhp/l, core=collection1, node_name=127.0.0.1:51449_uhp%2Fl}
[junit4] 2> 2716676 T7562 C1386 P51449 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 2716676 T7562 C1386 P51449 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4] 2> 2716676 T7562 C1386 P51449 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4] 2> 2716676 T7562 C1386 P51449 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2716676 T7560 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2716680 T7485 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2716681 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2716684 T7523 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true
[junit4] 2> 2717064 T7485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2717070 T7485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51453
[junit4] 2> 2717074 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2717074 T7485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2717075 T7485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ShardSplitTest-jetty4-1382682138986
[junit4] 2> 2717075 T7485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-jetty4-1382682138986/'
[junit4] 2> 2717115 T7485 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty4-1382682138986/solr.xml
[junit4] 2> 2717209 T7485 oasc.CoreContainer.<init> New CoreContainer 1967436185
[junit4] 2> 2717210 T7485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ShardSplitTest-jetty4-1382682138986/]
[junit4] 2> 2717211 T7485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2717212 T7485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2717212 T7485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2717212 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2717212 T7485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2717213 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2717214 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2717214 T7485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2717214 T7485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2717219 T7485 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2717219 T7485 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2717220 T7485 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51436/solr
[junit4] 2> 2717220 T7485 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2717222 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2717231 T7574 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3b882275 name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2717231 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2717237 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2717242 T7576 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@361509a6 name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2717242 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2717256 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2718089 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2718091 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "base_url":"http://127.0.0.1:51449/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51449_uhp%2Fl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node3"}
[junit4] 2> 2718108 T7559 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718109 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718110 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718110 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718110 T7576 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718111 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718269 T7485 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51453_uhp%2Fl
[junit4] 2> 2718272 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51453_uhp%2Fl
[junit4] 2> 2718280 T7576 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2718281 T7559 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2718282 T7514 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2718282 T7543 oascc.ZkS
[...truncated too long message...]
/overseer_elect/election/90615455191400454-127.0.0.1:51443_uhp%2Fl-n_0000000001 (0)
[junit4] 1> /solr/overseer_elect/election/90615455191400456-127.0.0.1:51446_uhp%2Fl-n_0000000002 (0)
[junit4] 1> /solr/overseer_elect/election/90615455191400460-127.0.0.1:51453_uhp%2Fl-n_0000000004 (0)
[junit4] 1> /solr/overseer_elect/election/90615455191400451-127.0.0.1:51439_uhp%2Fl-n_0000000000 (0)
[junit4] 1> /solr/overseer_elect/election/90615455191400458-127.0.0.1:51449_uhp%2Fl-n_0000000003 (0)
[junit4] 1> /solr/overseer_elect/leader (0)
[junit4] 1> DATA:
[junit4] 1> {"id":"90615455191400451-127.0.0.1:51439_uhp%2Fl-n_0000000000"}
[junit4] 1> /zookeeper (1)
[junit4] 1> DATA:
[junit4] 1>
[junit4] 1>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ShardSplitTest -Dtests.method=testDistribSearch -Dtests.seed=26991E9917156F3 -Dtests.slow=true -Dtests.locale=es_SV -Dtests.timezone=Africa/Tunis -Dtests.file.encoding=UTF-8
[junit4] FAILURE 81.2s | ShardSplitTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Wrong doc count on shard1_0 expected:<130> but was:<82>
[junit4] > at __randomizedtesting.SeedInfo.seed([26991E9917156F3:838F1FF1E62E36CF]:0)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:477)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.splitByUniqueKeyTest(ShardSplitTest.java:249)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:113)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 2775478 T7485 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 81237 T7484 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> 2776659 T7576 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: test params are: codec=Lucene41, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=es_SV, timezone=Africa/Tunis
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_45 (64-bit)/cpus=2,threads=1,free=315517728,total=434110464
[junit4] 2> NOTE: All tests run in this JVM: [DefaultValueUpdateProcessorTest, TestSuggestSpellingConverter, HighlighterTest, ClusterStateTest, RecoveryZkTest, WordBreakSolrSpellCheckerTest, TestPseudoReturnFields, CurrencyFieldXmlFileTest, AliasIntegrationTest, DateMathParserTest, CircularListTest, TestLMJelinekMercerSimilarityFactory, PreAnalyzedFieldTest, TestHashPartitioner, TestPHPSerializedResponseWriter, UpdateRequestProcessorFactoryTest, NumericFieldsTest, TestFastLRUCache, TestCopyFieldCollectionResource, TestArbitraryIndexDir, TestCoreContainer, DeleteShardTest, TestSearchPerf, BadCopyFieldTest, SpellingQueryConverterTest, TestJoin, TestQueryUtils, TestAnalyzedSuggestions, HdfsSyncSliceTest, QueryElevationComponentTest, ZkNodePropsTest, TestQueryTypes, TestLFUCache, AutoCommitTest, CopyFieldTest, TimeZoneUtilsTest, TestReplicationHandler, TestBadConfig, CoreContainerCoreInitFailuresTest, TestPerFieldSimilarity, TestSolrXml, SolrRequestParserTest, TestDynamicFieldResource, ResponseLogComponentTest, ShardRoutingTest, DirectSolrSpellCheckerTest, BadComponentTest, TestWordDelimiterFilterFactory, ConvertedLegacyTest, SolrInfoMBeanTest, IndexBasedSpellCheckerTest, EchoParamsTest, TestRandomMergePolicy, HighlighterConfigTest, SOLR749Test, NotRequiredUniqueKeyTest, TestDefaultSearchFieldResource, ReturnFieldsTest, TestSurroundQueryParser, MultiTermTest, TestFieldCollectionResource, OpenExchangeRatesOrgProviderTest, TestUtils, SortByFunctionTest, BasicDistributedZk2Test, PathHierarchyTokenizerFactoryTest, TestSweetSpotSimilarityFactory, StatelessScriptUpdateProcessorFactoryTest, TestQuerySenderNoQuery, HdfsLockFactoryTest, TestDistributedSearch, SuggesterFSTTest, BasicZkTest, OutputWriterTest, TestStressVersions, TestSolrQueryParserResource, ResourceLoaderTest, TestQuerySenderListener, TestExtendedDismaxParser, TestSchemaVersionResource, HdfsRecoveryZkTest, TermVectorComponentTest, DocumentBuilderTest, TestSolr4Spatial, TestRTGBase, TestCharFilters, DocValuesMissingTest, MBeansHandlerTest, BJQParserTest, ShardRoutingCustomTest, TestCSVLoader, TestFastWriter, TestDistributedGrouping, HdfsChaosMonkeySafeLeaderTest, TestFieldTypeCollectionResource, IndexSchemaRuntimeFieldTest, TestTrie, RequestHandlersTest, TestFoldingMultitermQuery, CoreAdminHandlerTest, TestShardHandlerFactory, BasicFunctionalityTest, TestCSVResponseWriter, UniqFieldsUpdateProcessorFactoryTest, SliceStateUpdateTest, TestCollationField, DirectUpdateHandlerOptimizeTest, TestManagedSchemaFieldResource, SolrTestCaseJ4Test, TestNRTOpen, TestClassNameShortening, ChaosMonkeySafeLeaderTest, TestSolrQueryParserDefaultOperatorResource, TestBinaryResponseWriter, TestFuzzyAnalyzedSuggestions, SolrIndexSplitterTest, QueryEqualityTest, CollectionsAPIDistributedZkTest, SuggesterTSTTest, LegacyHTMLStripCharFilterTest, TestDocumentBuilder, ExternalFileFieldSortTest, TestBM25SimilarityFactory, AssignTest, TestRecoveryHdfs, TestPhraseSuggestions, TestNoOpRegenerator, HdfsDirectoryTest, PeerSyncTest, TestIndexSearcher, TestSchemaSimilarityResource, TestMultiCoreConfBootstrap, RAMDirectoryFactoryTest, TestNumberUtils, LoggingHandlerTest, TestRemoteStreaming, UnloadDistributedZkTest, LukeRequestHandlerTest, XmlUpdateRequestHandlerTest, TermsComponentTest, TestSolrQueryParser, TestRealTimeGet, PrimUtilsTest, RegexBoostProcessorTest, TestStressRecovery, ZkCLITest, BadIndexSchemaTest, SuggesterWFSTTest, CurrencyFieldOpenExchangeTest, SynonymTokenizerTest, SliceStateTest, TestUpdate, TestDFRSimilarityFactory, TestDynamicFieldCollectionResource, TestSolrXmlPersistor, StatsComponentTest, TestJmxMonitoredMap, TestGroupingSearch, FileBasedSpellCheckerTest, BasicDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, TestFaceting, TestRecovery, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestStressReorder, TestReload, HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery, SimpleFacetsTest, SolrCoreTest, SpellCheckComponentTest, SolrCmdDistributorTest, TestSort, TestFiltering, TestFunctionQuery, TestLazyCores, DirectUpdateHandlerTest, OverseerCollectionProcessorTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, SimplePostToolTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, DocValuesTest, SuggesterTest, TestStressLucene, SpatialFilterTest, PolyFieldTest, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, DocValuesMultiTest, DistanceFunctionTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, DebugComponentTest, CacheHeaderTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, TestWriterPerf, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, TestOmitPositions, TestValueSourceCache, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, SolrPluginUtilsTest, IndexSchemaTest, TestJmxIntegration, JSONWriterTest, QueryParsingTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, PingRequestHandlerTest, CSVRequestHandlerTest, TestComponentsName, SearchHandlerTest, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, SolrIndexConfigTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, SampleTest, TestDocSet, TestBinaryField, MinimalSchemaTest, TestElisionMultitermQuery, TestConfig, TestSolrCoreProperties, TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestLuceneMatchVersion, SpellPossibilityIteratorTest, TestCodecSupport, TestXIncludeConfig, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestDefaultSimilarityFactory, TestFastOutputStream, ScriptEngineTest, URLClassifyProcessorTest, PluginInfoTest, ChaosMonkeyNothingIsSafeTest, TestSolrXMLSerializer, TestSystemIdResolver, DateFieldTest, DOMUtilTest, TestSolrJ, TestLRUCache, SystemInfoHandlerTest, UUIDFieldTest, FileUtilsTest, CustomCollectionTest, ShardSplitTest]
[junit4] Completed in 82.55s, 1 test, 1 failure <<< FAILURES!
[...truncated 114 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:422: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:402: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:483: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1259: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:902: There were test failures: 327 suites, 1451 tests, 1 failure, 40 ignored (5 assumptions)
Total time: 82 minutes 12 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure