lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Shalin Shekhar Mangar <sha...@apache.org>
Subject Re: [JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.7.0) - Build # 646 - Still Failing!
Date Fri, 12 Jul 2013 14:29:29 GMT
I'll investigate this failure.
On 12 Jul 2013 19:08, "Policeman Jenkins Server" <jenkins@thetaphi.de>
wrote:

> Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/646/
> Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
>
> 1 tests failed.
> REGRESSION:  org.apache.solr.cloud.ShardSplitTest.testDistribSearch
>
> Error Message:
> shard1_1 is not consistent expected:<72> but was:<71>
>
> Stack Trace:
> java.lang.AssertionError: shard1_1 is not consistent expected:<72> but
> was:<71>
>         at
> __randomizedtesting.SeedInfo.seed([7C4FC34DFE0A3738:FDA94D5589555704]: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.checkSubShardConsistency(ShardSplitTest.java:247)
>         at
> org.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:207)
>         at
> org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:173)
>         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:724)
>
>
>
>
> Build Log:
> [...truncated 9614 lines...]
>    [junit4] Suite: org.apache.solr.cloud.ShardSplitTest
>    [junit4]   2> 2066657 T4277
> oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext
> system property: /
>    [junit4]   2> 2066666 T4277 oas.SolrTestCaseJ4.setUp ###Starting
> testDistribSearch
>    [junit4]   2> Creating dataDir:
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ShardSplitTest-1373634244959
>    [junit4]   2> 2066668 T4277 oasc.ZkTestServer.run STARTING ZK TEST
> SERVER
>    [junit4]   2> 2066669 T4278
> oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
>    [junit4]   2> 2066674 T4277 oasc.ZkTestServer.run start zk server on
> port:50289
>    [junit4]   2> 2066681 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2066688 T4284 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@f402c3name:ZooKeeperConnection Watcher:
> 127.0.0.1:50289 got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2066688 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2066689 T4277 oascc.SolrZkClient.makePath makePath: /solr
>    [junit4]   2> 2066707 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2066711 T4286 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@1a016accname:ZooKeeperConnection Watcher:
> 127.0.0.1:50289/solr got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2066711 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2066712 T4277 oascc.SolrZkClient.makePath makePath:
> /collections/collection1
>    [junit4]   2> 2066724 T4277 oascc.SolrZkClient.makePath makePath:
> /collections/collection1/shards
>    [junit4]   2> 2066732 T4277 oascc.SolrZkClient.makePath makePath:
> /collections/control_collection
>    [junit4]   2> 2066738 T4277 oascc.SolrZkClient.makePath makePath:
> /collections/control_collection/shards
>    [junit4]   2> 2066746 T4277 oasc.AbstractZkTestCase.putConfig put
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml
> to /configs/conf1/solrconfig.xml
>    [junit4]   2> 2066747 T4277 oascc.SolrZkClient.makePath makePath:
> /configs/conf1/solrconfig.xml
>    [junit4]   2> 2066764 T4277 oasc.AbstractZkTestCase.putConfig put
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/schema15.xml
> to /configs/conf1/schema.xml
>    [junit4]   2> 2066765 T4277 oascc.SolrZkClient.makePath makePath:
> /configs/conf1/schema.xml
>    [junit4]   2> 2066773 T4277 oasc.AbstractZkTestCase.putConfig put
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt
> to /configs/conf1/stopwords.txt
>    [junit4]   2> 2066774 T4277 oascc.SolrZkClient.makePath makePath:
> /configs/conf1/stopwords.txt
>    [junit4]   2> 2066782 T4277 oasc.AbstractZkTestCase.putConfig put
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt
> to /configs/conf1/protwords.txt
>    [junit4]   2> 2066783 T4277 oascc.SolrZkClient.makePath makePath:
> /configs/conf1/protwords.txt
>    [junit4]   2> 2066790 T4277 oasc.AbstractZkTestCase.putConfig put
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml
> to /configs/conf1/currency.xml
>    [junit4]   2> 2066791 T4277 oascc.SolrZkClient.makePath makePath:
> /configs/conf1/currency.xml
>    [junit4]   2> 2066800 T4277 oasc.AbstractZkTestCase.putConfig put
> /Users/jenkins/jenkins-slave/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> 2066801 T4277 oascc.SolrZkClient.makePath makePath:
> /configs/conf1/open-exchange-rates.json
>    [junit4]   2> 2066809 T4277 oasc.AbstractZkTestCase.putConfig put
> /Users/jenkins/jenkins-slave/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> 2066810 T4277 oascc.SolrZkClient.makePath makePath:
> /configs/conf1/mapping-ISOLatin1Accent.txt
>    [junit4]   2> 2066824 T4277 oasc.AbstractZkTestCase.putConfig put
> /Users/jenkins/jenkins-slave/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> 2066825 T4277 oascc.SolrZkClient.makePath makePath:
> /configs/conf1/old_synonyms.txt
>    [junit4]   2> 2066832 T4277 oasc.AbstractZkTestCase.putConfig put
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt
> to /configs/conf1/synonyms.txt
>    [junit4]   2> 2066833 T4277 oascc.SolrZkClient.makePath makePath:
> /configs/conf1/synonyms.txt
>    [junit4]   2> 2067299 T4277 oejs.Server.doStart jetty-8.1.10.v20130312
>    [junit4]   2> 2067310 T4277 oejs.AbstractConnector.doStart Started
> SelectChannelConnector@127.0.0.1:50292
>    [junit4]   2> 2067311 T4277 oass.SolrDispatchFilter.init
> SolrDispatchFilter.init()
>    [junit4]   2> 2067312 T4277 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
>    [junit4]   2> 2067312 T4277 oasc.SolrResourceLoader.locateSolrHome
> using system property solr.solr.home:
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-controljetty-1373634245134
>    [junit4]   2> 2067313 T4277 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory:
> '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-controljetty-1373634245134/'
>    [junit4]   2> 2067363 T4277 oasc.ConfigSolr.fromFile Loading container
> configuration from
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-controljetty-1373634245134/solr.xml
>    [junit4]   2> 2067471 T4277 oasc.CoreContainer.<init> New CoreContainer
> 1709008594
>    [junit4]   2> 2067471 T4277 oasc.CoreContainer.load Loading cores into
> CoreContainer
> [instanceDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-controljetty-1373634245134/]
>    [junit4]   2> 2067475 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 120000
>    [junit4]   2> 2067475 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
>    [junit4]   2> 2067477 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 15000
>    [junit4]   2> 2067477 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
>    [junit4]   2> 2067478 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
>    [junit4]   2> 2067478 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
>    [junit4]   2> 2067479 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
>    [junit4]   2> 2067479 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
>    [junit4]   2> 2067480 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
>    [junit4]   2> 2067480 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
>    [junit4]   2> 2067505 T4277 oasl.LogWatcher.newRegisteredLogWatcher
> Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
>    [junit4]   2> 2067506 T4277 oasc.ZkContainer.initZooKeeper Zookeeper
> client=127.0.0.1:50289/solr
>    [junit4]   2> 2067507 T4277 oasc.ZkController.checkChrootPath zkHost
> includes chroot
>    [junit4]   2> 2067510 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2067514 T4297 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@26d77937name:ZooKeeperConnection Watcher:
> 127.0.0.1:50289 got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2067515 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2067521 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
>    [junit4]   2> 2067538 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2067543 T4299 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@1f76d3dfname:ZooKeeperConnection Watcher:
> 127.0.0.1:50289/solr got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2067544 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2067549 T4277 oascc.SolrZkClient.makePath makePath:
> /overseer/queue
>    [junit4]   2> 2067565 T4277 oascc.SolrZkClient.makePath makePath:
> /overseer/collection-queue-work
>    [junit4]   2> 2067573 T4277 oascc.SolrZkClient.makePath makePath:
> /live_nodes
>    [junit4]   2> 2067578 T4277 oasc.ZkController.createEphemeralLiveNode
> Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50292_
>    [junit4]   2> 2067580 T4277 oascc.SolrZkClient.makePath makePath:
> /live_nodes/127.0.0.1:50292_
>    [junit4]   2> 2067589 T4277 oascc.SolrZkClient.makePath makePath:
> /overseer_elect/election
>    [junit4]   2> 2067601 T4277 oascc.SolrZkClient.makePath makePath:
> /overseer_elect/leader
>    [junit4]   2> 2067607 T4277 oasc.Overseer.start Overseer
> (id=90022493878222851-127.0.0.1:50292_-n_0000000000) starting
>    [junit4]   2> 2067615 T4277 oascc.SolrZkClient.makePath makePath:
> /overseer/queue-work
>    [junit4]   2> 2067632 T4301 oasc.OverseerCollectionProcessor.run
> Process current queue of collection creations
>    [junit4]   2> 2067636 T4277 oascc.SolrZkClient.makePath makePath:
> /clusterstate.json
>    [junit4]   2> 2067642 T4277 oascc.SolrZkClient.makePath makePath:
> /aliases.json
>    [junit4]   2> 2067648 T4277
> oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster
> state from ZooKeeper...
>    [junit4]   2> 2067664 T4300 oasc.Overseer$ClusterStateUpdater.run
> Starting to work on the main queue
>    [junit4]   2> 2067668 T4302 oasc.ZkController.publish publishing
> core=collection1 state=down
>    [junit4]   2> 2067668 T4302 oasc.ZkController.publish numShards not
> found on descriptor - reading it from system property
>    [junit4]   2> 2067670 T4302 oasc.ZkController.waitForCoreNodeName look
> for our core node name
>    [junit4]   2> 2069178 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2069181 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1
> message={
>    [junit4]   2>          "operation":"state",
>    [junit4]   2>          "core_node_name":"core_node1",
>    [junit4]   2>          "numShards":"1",
>    [junit4]   2>          "shard":null,
>    [junit4]   2>          "roles":null,
>    [junit4]   2>          "state":"down",
>    [junit4]   2>          "shard_state":"active",
>    [junit4]   2>          "core":"collection1",
>    [junit4]   2>          "collection":"control_collection",
>    [junit4]   2>          "shard_range":null,
>    [junit4]   2>          "node_name":"127.0.0.1:50292_",
>    [junit4]   2>          "base_url":"http://127.0.0.1:50292"}
>    [junit4]   2> 2069182 T4300
> oasc.Overseer$ClusterStateUpdater.createCollection Create collection
> control_collection with numShards 1
>    [junit4]   2> 2069182 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard
> shard=shard1
>    [junit4]   2> 2069190 T4299 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> 2069673 T4302 oasc.ZkController.waitForShardId waiting to
> find shard id in clusterstate for collection1
>    [junit4]   2> 2069674 T4302 oasc.CoreContainer.create Creating SolrCore
> 'collection1' using instanceDir:
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-controljetty-1373634245134/collection1
>    [junit4]   2> 2069674 T4302 oasc.ZkController.createCollectionZkNode
> Check for collection zkNode:control_collection
>    [junit4]   2> 2069676 T4302 oasc.ZkController.createCollectionZkNode
> Collection zkNode exists
>    [junit4]   2> 2069676 T4302 oasc.ZkController.readConfigName Load
> collection config from:/collections/control_collection
>    [junit4]   2> 2069679 T4302 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory:
> '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-controljetty-1373634245134/collection1/'
>    [junit4]   2> 2069681 T4302 oasc.SolrResourceLoader.replaceClassLoader
> Adding
> 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-controljetty-1373634245134/collection1/lib/classes/'
> to classloader
>    [junit4]   2> 2069682 T4302 oasc.SolrResourceLoader.replaceClassLoader
> Adding
> 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-controljetty-1373634245134/collection1/lib/README'
> to classloader
>    [junit4]   2> 2069818 T4302 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_50
>    [junit4]   2> 2069914 T4302 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig.xml
>    [junit4]   2> 2069918 T4302 oass.IndexSchema.readSchema Reading Solr
> Schema from schema.xml
>    [junit4]   2> 2069931 T4302 oass.IndexSchema.readSchema [collection1]
> Schema name=test
>    [junit4]   2> 2070810 T4302 oass.IndexSchema.readSchema default search
> field in schema is text
>    [junit4]   2> 2070815 T4302 oass.IndexSchema.readSchema unique key
> field: id
>    [junit4]   2> 2070823 T4302 oass.FileExchangeRateProvider.reload
> Reloading exchange rates from file currency.xml
>    [junit4]   2> 2070831 T4302 oass.FileExchangeRateProvider.reload
> Reloading exchange rates from file currency.xml
>    [junit4]   2> 2070902 T4302 oasc.SolrCore.initDirectoryFactory
> org.apache.solr.core.MockDirectoryFactory
>    [junit4]   2> 2070903 T4302 oasc.SolrCore.<init> [collection1] Opening
> new SolrCore at
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-controljetty-1373634245134/collection1/,
> dataDir=./org.apache.solr.cloud.ShardSplitTest-1373634244959/control/data/
>    [junit4]   2> 2070903 T4302 oasc.JmxMonitoredMap.<init> JMX monitoring
> is enabled. Adding Solr mbeans to JMX Server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@9df4f6e
>    [junit4]   2> 2070904 T4302 oasc.CachingDirectoryFactory.get return new
> directory for
> ./org.apache.solr.cloud.ShardSplitTest-1373634244959/control/data
>    [junit4]   2> 2070905 T4302 oasc.SolrCore.getNewIndexDir New index
> directory detected: old=null
> new=./org.apache.solr.cloud.ShardSplitTest-1373634244959/control/data/index/
>    [junit4]   2> 2070905 T4302 oasc.SolrCore.initIndex WARN [collection1]
> Solr index directory
> './org.apache.solr.cloud.ShardSplitTest-1373634244959/control/data/index'
> doesn't exist. Creating new index...
>    [junit4]   2> 2070906 T4302 oasc.CachingDirectoryFactory.get return new
> directory for
> ./org.apache.solr.cloud.ShardSplitTest-1373634244959/control/data/index
>    [junit4]   2> 2070912 T4302 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits: num=1
>    [junit4]   2>
>  commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@24b159b2lockFactory=org.apache.lucene.store.NativeFSLockFactory@3c904a42
> ),segFN=segments_1,generation=1}
>    [junit4]   2> 2070912 T4302 oasc.SolrDeletionPolicy.updateCommits
> newest commit generation = 1
>    [junit4]   2> 2070919 T4302 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "dedupe"
>    [junit4]   2> 2070920 T4302 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "stored_sig"
>    [junit4]   2> 2070921 T4302 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "distrib-dup-test-chain-implicit"
>    [junit4]   2> 2070922 T4302 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
>    [junit4]   2> 2070922 T4302 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
>    [junit4]   2> 2070923 T4302 oasc.RequestHandlers.initHandlersFromConfig
> adding lazy requestHandler: solr.ReplicationHandler
>    [junit4]   2> 2070926 T4302 oasc.RequestHandlers.initHandlersFromConfig
> created /replication: solr.ReplicationHandler
>    [junit4]   2> 2070928 T4302 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
>    [junit4]   2> 2070928 T4302 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
>    [junit4]   2> 2070942 T4302 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
>    [junit4]   2> 2070953 T4302 oass.SolrIndexSearcher.<init> Opening
> Searcher@6ff50fc7 main
>    [junit4]   2> 2070960 T4302 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
>    [junit4]   2> 2070960 T4302 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
>    [junit4]   2> 2070964 T4303 oasc.SolrCore.registerSearcher
> [collection1] Registered new searcher Searcher@6ff50fc7main{StandardDirectoryReader(segments_1:1)}
>    [junit4]   2> 2070966 T4302 oasc.CoreContainer.registerCore registering
> core: collection1
>    [junit4]   2> 2070966 T4302 oasc.ZkController.register Register replica
> - core:collection1 address:http://127.0.0.1:50292collection:control_collection shard:shard1
>    [junit4]   2> 2070966 T4302 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
>    [junit4]   2> 2070987 T4302 oascc.SolrZkClient.makePath makePath:
> /collections/control_collection/leader_elect/shard1/election
>    [junit4]   2> 2071002 T4302
> oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process
> for shard shard1
>    [junit4]   2> 2071009 T4302
> oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas
> found to continue.
>    [junit4]   2> 2071010 T4302
> oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader -
> try and sync
>    [junit4]   2> 2071010 T4302 oasc.SyncStrategy.sync Sync replicas to
> http://127.0.0.1:50292/collection1/
>    [junit4]   2> 2071010 T4302 oasc.SyncStrategy.syncReplicas Sync Success
> - now sync replicas to me
>    [junit4]   2> 2071011 T4302 oasc.SyncStrategy.syncToMe
> http://127.0.0.1:50292/collection1/ has no replicas
>    [junit4]   2> 2071011 T4302
> oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
> http://127.0.0.1:50292/collection1/ shard1
>    [junit4]   2> 2071011 T4302 oascc.SolrZkClient.makePath makePath:
> /collections/control_collection/leaders/shard1
>    [junit4]   2> 2072212 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2072230 T4299 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> 2072270 T4302 oasc.ZkController.register We are
> http://127.0.0.1:50292/collection1/ and leader is
> http://127.0.0.1:50292/collection1/
>    [junit4]   2> 2072270 T4302 oasc.ZkController.checkRecovery I am the
> leader, no recovery necessary
>    [junit4]   2> 2072271 T4302 oasc.ZkController.publish publishing
> core=collection1 state=active
>    [junit4]   2> 2072271 T4302 oasc.ZkController.publish numShards not
> found on descriptor - reading it from system property
>    [junit4]   2> 2072274 T4302 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2072278 T4277 oass.SolrDispatchFilter.init
> user.dir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
>    [junit4]   2> 2072279 T4277 oass.SolrDispatchFilter.init
> SolrDispatchFilter.init() done
>    [junit4]   2> 2072279 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
>    [junit4]   2> 2072297 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client, config:
>    [junit4]   2> 2072300 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2072304 T4306 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@2988188dname:ZooKeeperConnection Watcher:
> 127.0.0.1:50289/solr got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2072305 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2072310 T4277
> oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster
> state from ZooKeeper...
>    [junit4]   2> 2072315 T4277 oasc.ChaosMonkey.monkeyLog monkey: init -
> expire sessions:true cause connection loss:true
>    [junit4]   2> 2072868 T4277 oejs.Server.doStart jetty-8.1.10.v20130312
>    [junit4]   2> 2072877 T4277 oejs.AbstractConnector.doStart Started
> SelectChannelConnector@127.0.0.1:50296
>    [junit4]   2> 2072879 T4277 oass.SolrDispatchFilter.init
> SolrDispatchFilter.init()
>    [junit4]   2> 2072882 T4277 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
>    [junit4]   2> 2072882 T4277 oasc.SolrResourceLoader.locateSolrHome
> using system property solr.solr.home:
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1373634250608
>    [junit4]   2> 2072883 T4277 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory:
> '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1373634250608/'
>    [junit4]   2> 2072930 T4277 oasc.ConfigSolr.fromFile Loading container
> configuration from
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1373634250608/solr.xml
>    [junit4]   2> 2073033 T4277 oasc.CoreContainer.<init> New CoreContainer
> 684133584
>    [junit4]   2> 2073034 T4277 oasc.CoreContainer.load Loading cores into
> CoreContainer
> [instanceDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1373634250608/]
>    [junit4]   2> 2073036 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 120000
>    [junit4]   2> 2073037 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
>    [junit4]   2> 2073038 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 15000
>    [junit4]   2> 2073038 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
>    [junit4]   2> 2073039 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
>    [junit4]   2> 2073039 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
>    [junit4]   2> 2073040 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
>    [junit4]   2> 2073040 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
>    [junit4]   2> 2073041 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
>    [junit4]   2> 2073041 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
>    [junit4]   2> 2073064 T4277 oasl.LogWatcher.newRegisteredLogWatcher
> Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
>    [junit4]   2> 2073065 T4277 oasc.ZkContainer.initZooKeeper Zookeeper
> client=127.0.0.1:50289/solr
>    [junit4]   2> 2073066 T4277 oasc.ZkController.checkChrootPath zkHost
> includes chroot
>    [junit4]   2> 2073069 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2073073 T4317 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@4705b946name:ZooKeeperConnection Watcher:
> 127.0.0.1:50289 got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2073074 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2073081 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
>    [junit4]   2> 2073097 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2073103 T4319 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@4211493bname:ZooKeeperConnection Watcher:
> 127.0.0.1:50289/solr got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2073103 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2073118 T4277
> oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster
> state from ZooKeeper...
>    [junit4]   2> 2073745 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2073747 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1
> message={
>    [junit4]   2>          "operation":"state",
>    [junit4]   2>          "core_node_name":"core_node1",
>    [junit4]   2>          "numShards":"1",
>    [junit4]   2>          "shard":"shard1",
>    [junit4]   2>          "roles":null,
>    [junit4]   2>          "state":"active",
>    [junit4]   2>          "shard_state":"active",
>    [junit4]   2>          "core":"collection1",
>    [junit4]   2>          "collection":"control_collection",
>    [junit4]   2>          "shard_range":null,
>    [junit4]   2>          "node_name":"127.0.0.1:50292_",
>    [junit4]   2>          "base_url":"http://127.0.0.1:50292"}
>    [junit4]   2> 2073757 T4299 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> 2073765 T4306 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> 2073769 T4319 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> 2074131 T4277 oasc.ZkController.createEphemeralLiveNode
> Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50296_
>    [junit4]   2> 2074134 T4277 oascc.SolrZkClient.makePath makePath:
> /live_nodes/127.0.0.1:50296_
>    [junit4]   2> 2074140 T4306 oascc.ZkStateReader$3.process Updating live
> nodes... (2)
>    [junit4]   2> 2074141 T4306 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 2)
>    [junit4]   2> 2074141 T4319 oascc.ZkStateReader$3.process Updating live
> nodes... (2)
>    [junit4]   2> 2074142 T4319 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 2)
>    [junit4]   2> 2074141 T4299 oascc.ZkStateReader$3.process Updating live
> nodes... (2)
>    [junit4]   2> 2074188 T4299 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 2)
>    [junit4]   2> 2074187 T4320 oasc.ZkController.publish publishing
> core=collection1 state=down
>    [junit4]   2> 2074188 T4320 oasc.ZkController.publish numShards not
> found on descriptor - reading it from system property
>    [junit4]   2> 2074193 T4320 oasc.ZkController.waitForCoreNodeName look
> for our core node name
>    [junit4]   2> 2075273 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2075276 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2
> message={
>    [junit4]   2>          "operation":"state",
>    [junit4]   2>          "core_node_name":"core_node1",
>    [junit4]   2>          "numShards":"2",
>    [junit4]   2>          "shard":null,
>    [junit4]   2>          "roles":null,
>    [junit4]   2>          "state":"down",
>    [junit4]   2>          "shard_state":"active",
>    [junit4]   2>          "core":"collection1",
>    [junit4]   2>          "collection":"collection1",
>    [junit4]   2>          "shard_range":null,
>    [junit4]   2>          "node_name":"127.0.0.1:50296_",
>    [junit4]   2>          "base_url":"http://127.0.0.1:50296"}
>    [junit4]   2> 2075277 T4300
> oasc.Overseer$ClusterStateUpdater.createCollection Create collection
> collection1 with numShards 2
>    [junit4]   2> 2075277 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard
> shard=shard1
>    [junit4]   2> 2075287 T4299 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> 2075291 T4306 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> 2075292 T4319 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> 2076197 T4320 oasc.ZkController.waitForShardId waiting to
> find shard id in clusterstate for collection1
>    [junit4]   2> 2076198 T4320 oasc.CoreContainer.create Creating SolrCore
> 'collection1' using instanceDir:
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1373634250608/collection1
>    [junit4]   2> 2076198 T4320 oasc.ZkController.createCollectionZkNode
> Check for collection zkNode:collection1
>    [junit4]   2> 2076200 T4320 oasc.ZkController.createCollectionZkNode
> Collection zkNode exists
>    [junit4]   2> 2076201 T4320 oasc.ZkController.readConfigName Load
> collection config from:/collections/collection1
>    [junit4]   2> 2076204 T4320 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory:
> '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1373634250608/collection1/'
>    [junit4]   2> 2076205 T4320 oasc.SolrResourceLoader.replaceClassLoader
> Adding
> 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty1-1373634250608/collection1/lib/classes/'
> to classloader
>    [junit4]   2> 2076206 T4320 oasc.SolrResourceLoader.replaceClassLoader
> Adding
> 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty1-1373634250608/collection1/lib/README'
> to classloader
>    [junit4]   2> 2076293 T4320 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_50
>    [junit4]   2> 2076422 T4320 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig.xml
>    [junit4]   2> 2076425 T4320 oass.IndexSchema.readSchema Reading Solr
> Schema from schema.xml
>    [junit4]   2> 2076438 T4320 oass.IndexSchema.readSchema [collection1]
> Schema name=test
>    [junit4]   2> 2077282 T4320 oass.IndexSchema.readSchema default search
> field in schema is text
>    [junit4]   2> 2077287 T4320 oass.IndexSchema.readSchema unique key
> field: id
>    [junit4]   2> 2077290 T4320 oass.FileExchangeRateProvider.reload
> Reloading exchange rates from file currency.xml
>    [junit4]   2> 2077299 T4320 oass.FileExchangeRateProvider.reload
> Reloading exchange rates from file currency.xml
>    [junit4]   2> 2077366 T4320 oasc.SolrCore.initDirectoryFactory
> org.apache.solr.core.MockDirectoryFactory
>    [junit4]   2> 2077366 T4320 oasc.SolrCore.<init> [collection1] Opening
> new SolrCore at
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1373634250608/collection1/,
> dataDir=./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty1/
>    [junit4]   2> 2077367 T4320 oasc.JmxMonitoredMap.<init> JMX monitoring
> is enabled. Adding Solr mbeans to JMX Server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@9df4f6e
>    [junit4]   2> 2077368 T4320 oasc.CachingDirectoryFactory.get return new
> directory for ./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty1
>    [junit4]   2> 2077368 T4320 oasc.SolrCore.getNewIndexDir New index
> directory detected: old=null
> new=./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty1/index/
>    [junit4]   2> 2077369 T4320 oasc.SolrCore.initIndex WARN [collection1]
> Solr index directory
> './org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty1/index' doesn't
> exist. Creating new index...
>    [junit4]   2> 2077370 T4320 oasc.CachingDirectoryFactory.get return new
> directory for
> ./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty1/index
>    [junit4]   2> 2077374 T4320 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits: num=1
>    [junit4]   2>
>  commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@80e598flockFactory=org.apache.lucene.store.NativeFSLockFactory@23a550c7
> ),segFN=segments_1,generation=1}
>    [junit4]   2> 2077377 T4320 oasc.SolrDeletionPolicy.updateCommits
> newest commit generation = 1
>    [junit4]   2> 2077388 T4320 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "dedupe"
>    [junit4]   2> 2077389 T4320 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "stored_sig"
>    [junit4]   2> 2077390 T4320 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "distrib-dup-test-chain-implicit"
>    [junit4]   2> 2077391 T4320 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
>    [junit4]   2> 2077392 T4320 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
>    [junit4]   2> 2077392 T4320 oasc.RequestHandlers.initHandlersFromConfig
> adding lazy requestHandler: solr.ReplicationHandler
>    [junit4]   2> 2077393 T4320 oasc.RequestHandlers.initHandlersFromConfig
> created /replication: solr.ReplicationHandler
>    [junit4]   2> 2077393 T4320 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
>    [junit4]   2> 2077394 T4320 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
>    [junit4]   2> 2077409 T4320 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
>    [junit4]   2> 2077417 T4320 oass.SolrIndexSearcher.<init> Opening
> Searcher@621b040d main
>    [junit4]   2> 2077419 T4320 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
>    [junit4]   2> 2077419 T4320 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
>    [junit4]   2> 2077426 T4321 oasc.SolrCore.registerSearcher
> [collection1] Registered new searcher Searcher@621b040dmain{StandardDirectoryReader(segments_1:1)}
>    [junit4]   2> 2077430 T4320 oasc.CoreContainer.registerCore registering
> core: collection1
>    [junit4]   2> 2077430 T4320 oasc.ZkController.register Register replica
> - core:collection1 address:http://127.0.0.1:50296 collection:collection1
> shard:shard1
>    [junit4]   2> 2077430 T4320 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
>    [junit4]   2> 2077455 T4320 oascc.SolrZkClient.makePath makePath:
> /collections/collection1/leader_elect/shard1/election
>    [junit4]   2> 2077472 T4320
> oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process
> for shard shard1
>    [junit4]   2> 2077479 T4320
> oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas
> found to continue.
>    [junit4]   2> 2077479 T4320
> oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader -
> try and sync
>    [junit4]   2> 2077479 T4320 oasc.SyncStrategy.sync Sync replicas to
> http://127.0.0.1:50296/collection1/
>    [junit4]   2> 2077480 T4320 oasc.SyncStrategy.syncReplicas Sync Success
> - now sync replicas to me
>    [junit4]   2> 2077480 T4320 oasc.SyncStrategy.syncToMe
> http://127.0.0.1:50296/collection1/ has no replicas
>    [junit4]   2> 2077480 T4320
> oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
> http://127.0.0.1:50296/collection1/ shard1
>    [junit4]   2> 2077481 T4320 oascc.SolrZkClient.makePath makePath:
> /collections/collection1/leaders/shard1
>    [junit4]   2> 2078363 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2078381 T4306 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> 2078384 T4299 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> 2078389 T4319 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> 2078417 T4320 oasc.ZkController.register We are
> http://127.0.0.1:50296/collection1/ and leader is
> http://127.0.0.1:50296/collection1/
>    [junit4]   2> 2078417 T4320 oasc.ZkController.checkRecovery I am the
> leader, no recovery necessary
>    [junit4]   2> 2078418 T4320 oasc.ZkController.publish publishing
> core=collection1 state=active
>    [junit4]   2> 2078418 T4320 oasc.ZkController.publish numShards not
> found on descriptor - reading it from system property
>    [junit4]   2> 2078422 T4320 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2078426 T4277 oass.SolrDispatchFilter.init
> user.dir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
>    [junit4]   2> 2078427 T4277 oass.SolrDispatchFilter.init
> SolrDispatchFilter.init() done
>    [junit4]   2> 2078427 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
>    [junit4]   2> 2078857 T4277 oejs.Server.doStart jetty-8.1.10.v20130312
>    [junit4]   2> 2078865 T4277 oejs.AbstractConnector.doStart Started
> SelectChannelConnector@127.0.0.1:50299
>    [junit4]   2> 2078866 T4277 oass.SolrDispatchFilter.init
> SolrDispatchFilter.init()
>    [junit4]   2> 2078866 T4277 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
>    [junit4]   2> 2078867 T4277 oasc.SolrResourceLoader.locateSolrHome
> using system property solr.solr.home:
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty2-1373634256733
>    [junit4]   2> 2078867 T4277 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory:
> '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty2-1373634256733/'
>    [junit4]   2> 2078917 T4277 oasc.ConfigSolr.fromFile Loading container
> configuration from
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty2-1373634256733/solr.xml
>    [junit4]   2> 2079032 T4277 oasc.CoreContainer.<init> New CoreContainer
> 70299595
>    [junit4]   2> 2079033 T4277 oasc.CoreContainer.load Loading cores into
> CoreContainer
> [instanceDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty2-1373634256733/]
>    [junit4]   2> 2079035 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 120000
>    [junit4]   2> 2079036 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
>    [junit4]   2> 2079036 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 15000
>    [junit4]   2> 2079037 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
>    [junit4]   2> 2079037 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
>    [junit4]   2> 2079038 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
>    [junit4]   2> 2079038 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
>    [junit4]   2> 2079038 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
>    [junit4]   2> 2079039 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
>    [junit4]   2> 2079040 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
>    [junit4]   2> 2079059 T4277 oasl.LogWatcher.newRegisteredLogWatcher
> Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
>    [junit4]   2> 2079060 T4277 oasc.ZkContainer.initZooKeeper Zookeeper
> client=127.0.0.1:50289/solr
>    [junit4]   2> 2079060 T4277 oasc.ZkController.checkChrootPath zkHost
> includes chroot
>    [junit4]   2> 2079064 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2079070 T4333 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@10a8ee2fname:ZooKeeperConnection Watcher:
> 127.0.0.1:50289 got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2079071 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2079075 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
>    [junit4]   2> 2079088 T4279 oazs.NIOServerCnxn.doIO WARN caught end of
> stream exception EndOfStreamException: Unable to read additional data from
> client sessionid 0x13fd2fb31680007, likely client has closed socket
>    [junit4]   2>        at
> org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
>    [junit4]   2>        at
> org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
>    [junit4]   2>        at java.lang.Thread.run(Thread.java:724)
>    [junit4]   2>
>    [junit4]   2> 2079098 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2079101 T4335 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@7b7c4a74name:ZooKeeperConnection Watcher:
> 127.0.0.1:50289/solr got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2079102 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2079115 T4277
> oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster
> state from ZooKeeper...
>    [junit4]   2> 2079903 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2079906 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2
> message={
>    [junit4]   2>          "operation":"state",
>    [junit4]   2>          "core_node_name":"core_node1",
>    [junit4]   2>          "numShards":"2",
>    [junit4]   2>          "shard":"shard1",
>    [junit4]   2>          "roles":null,
>    [junit4]   2>          "state":"active",
>    [junit4]   2>          "shard_state":"active",
>    [junit4]   2>          "core":"collection1",
>    [junit4]   2>          "collection":"collection1",
>    [junit4]   2>          "shard_range":null,
>    [junit4]   2>          "node_name":"127.0.0.1:50296_",
>    [junit4]   2>          "base_url":"http://127.0.0.1:50296"}
>    [junit4]   2> 2079942 T4335 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> 2079948 T4299 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> 2079949 T4319 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> 2079949 T4306 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> 2080125 T4277 oasc.ZkController.createEphemeralLiveNode
> Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50299_
>    [junit4]   2> 2080127 T4277 oascc.SolrZkClient.makePath makePath:
> /live_nodes/127.0.0.1:50299_
>    [junit4]   2> 2080135 T4299 oascc.ZkStateReader$3.process Updating live
> nodes... (3)
>    [junit4]   2> 2080136 T4299 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 3)
>    [junit4]   2> 2080136 T4335 oascc.ZkStateReader$3.process Updating live
> nodes... (3)
>    [junit4]   2> 2080136 T4335 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 3)
>    [junit4]   2> 2080150 T4319 oascc.ZkStateReader$3.process Updating live
> nodes... (3)
>    [junit4]   2> 2080151 T4319 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 3)
>    [junit4]   2> 2080150 T4306 oascc.ZkStateReader$3.process Updating live
> nodes... (3)
>    [junit4]   2> 2080152 T4306 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 3)
>    [junit4]   2> 2080161 T4336 oasc.ZkController.publish publishing
> core=collection1 state=down
>    [junit4]   2> 2080162 T4336 oasc.ZkController.publish numShards not
> found on descriptor - reading it from system property
>    [junit4]   2> 2080164 T4336 oasc.ZkController.waitForCoreNodeName look
> for our core node name
>    [junit4]   2> 2081467 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2081472 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2
> message={
>    [junit4]   2>          "operation":"state",
>    [junit4]   2>          "core_node_name":"core_node2",
>    [junit4]   2>          "numShards":"2",
>    [junit4]   2>          "shard":null,
>    [junit4]   2>          "roles":null,
>    [junit4]   2>          "state":"down",
>    [junit4]   2>          "shard_state":"active",
>    [junit4]   2>          "core":"collection1",
>    [junit4]   2>          "collection":"collection1",
>    [junit4]   2>          "shard_range":null,
>    [junit4]   2>          "node_name":"127.0.0.1:50299_",
>    [junit4]   2>          "base_url":"http://127.0.0.1:50299"}
>    [junit4]   2> 2081472 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Collection already exists
> with numShards=2
>    [junit4]   2> 2081473 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard
> shard=shard2
>    [junit4]   2> 2081493 T4299 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> 2081493 T4335 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> 2081493 T4306 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> 2081494 T4319 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> 2082167 T4336 oasc.ZkController.waitForShardId waiting to
> find shard id in clusterstate for collection1
>    [junit4]   2> 2082167 T4336 oasc.CoreContainer.create Creating SolrCore
> 'collection1' using instanceDir:
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty2-1373634256733/collection1
>    [junit4]   2> 2082168 T4336 oasc.ZkController.createCollectionZkNode
> Check for collection zkNode:collection1
>    [junit4]   2> 2082169 T4336 oasc.ZkController.createCollectionZkNode
> Collection zkNode exists
>    [junit4]   2> 2082172 T4336 oasc.ZkController.readConfigName Load
> collection config from:/collections/collection1
>    [junit4]   2> 2082182 T4336 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory:
> '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty2-1373634256733/collection1/'
>    [junit4]   2> 2082184 T4336 oasc.SolrResourceLoader.replaceClassLoader
> Adding
> 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty2-1373634256733/collection1/lib/classes/'
> to classloader
>    [junit4]   2> 2082185 T4336 oasc.SolrResourceLoader.replaceClassLoader
> Adding
> 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty2-1373634256733/collection1/lib/README'
> to classloader
>    [junit4]   2> 2082292 T4336 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_50
>    [junit4]   2> 2082390 T4336 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig.xml
>    [junit4]   2> 2082396 T4336 oass.IndexSchema.readSchema Reading Solr
> Schema from schema.xml
>    [junit4]   2> 2082450 T4336 oass.IndexSchema.readSchema [collection1]
> Schema name=test
>    [junit4]   2> 2083410 T4336 oass.IndexSchema.readSchema default search
> field in schema is text
>    [junit4]   2> 2083415 T4336 oass.IndexSchema.readSchema unique key
> field: id
>    [junit4]   2> 2083418 T4336 oass.FileExchangeRateProvider.reload
> Reloading exchange rates from file currency.xml
>    [junit4]   2> 2083456 T4336 oass.FileExchangeRateProvider.reload
> Reloading exchange rates from file currency.xml
>    [junit4]   2> 2083524 T4336 oasc.SolrCore.initDirectoryFactory
> org.apache.solr.core.MockDirectoryFactory
>    [junit4]   2> 2083526 T4336 oasc.SolrCore.<init> [collection1] Opening
> new SolrCore at
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty2-1373634256733/collection1/,
> dataDir=./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty2/
>    [junit4]   2> 2083530 T4336 oasc.JmxMonitoredMap.<init> JMX monitoring
> is enabled. Adding Solr mbeans to JMX Server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@9df4f6e
>    [junit4]   2> 2083534 T4336 oasc.CachingDirectoryFactory.get return new
> directory for ./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty2
>    [junit4]   2> 2083534 T4336 oasc.SolrCore.getNewIndexDir New index
> directory detected: old=null
> new=./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty2/index/
>    [junit4]   2> 2083535 T4336 oasc.SolrCore.initIndex WARN [collection1]
> Solr index directory
> './org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty2/index' doesn't
> exist. Creating new index...
>    [junit4]   2> 2083536 T4336 oasc.CachingDirectoryFactory.get return new
> directory for
> ./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty2/index
>    [junit4]   2> 2083541 T4336 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits: num=1
>    [junit4]   2>
>  commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2bcab6bblockFactory=org.apache.lucene.store.NativeFSLockFactory@325e6883
> ),segFN=segments_1,generation=1}
>    [junit4]   2> 2083542 T4336 oasc.SolrDeletionPolicy.updateCommits
> newest commit generation = 1
>    [junit4]   2> 2083552 T4336 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "dedupe"
>    [junit4]   2> 2083553 T4336 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "stored_sig"
>    [junit4]   2> 2083554 T4336 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "distrib-dup-test-chain-implicit"
>    [junit4]   2> 2083555 T4336 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
>    [junit4]   2> 2083557 T4336 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
>    [junit4]   2> 2083557 T4336 oasc.RequestHandlers.initHandlersFromConfig
> adding lazy requestHandler: solr.ReplicationHandler
>    [junit4]   2> 2083558 T4336 oasc.RequestHandlers.initHandlersFromConfig
> created /replication: solr.ReplicationHandler
>    [junit4]   2> 2083559 T4336 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
>    [junit4]   2> 2083559 T4336 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
>    [junit4]   2> 2083577 T4336 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
>    [junit4]   2> 2083586 T4336 oass.SolrIndexSearcher.<init> Opening
> Searcher@6346a1c0 main
>    [junit4]   2> 2083594 T4336 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
>    [junit4]   2> 2083595 T4336 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
>    [junit4]   2> 2083602 T4337 oasc.SolrCore.registerSearcher
> [collection1] Registered new searcher Searcher@6346a1c0main{StandardDirectoryReader(segments_1:1)}
>    [junit4]   2> 2083603 T4336 oasc.CoreContainer.registerCore registering
> core: collection1
>    [junit4]   2> 2083604 T4336 oasc.ZkController.register Register replica
> - core:collection1 address:http://127.0.0.1:50299 collection:collection1
> shard:shard2
>    [junit4]   2> 2083604 T4336 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
>    [junit4]   2> 2083629 T4336 oascc.SolrZkClient.makePath makePath:
> /collections/collection1/leader_elect/shard2/election
>    [junit4]   2> 2083667 T4336
> oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process
> for shard shard2
>    [junit4]   2> 2083673 T4336
> oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas
> found to continue.
>    [junit4]   2> 2083673 T4336
> oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader -
> try and sync
>    [junit4]   2> 2083674 T4336 oasc.SyncStrategy.sync Sync replicas to
> http://127.0.0.1:50299/collection1/
>    [junit4]   2> 2083674 T4336 oasc.SyncStrategy.syncReplicas Sync Success
> - now sync replicas to me
>    [junit4]   2> 2083674 T4336 oasc.SyncStrategy.syncToMe
> http://127.0.0.1:50299/collection1/ has no replicas
>    [junit4]   2> 2083674 T4336
> oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader:
> http://127.0.0.1:50299/collection1/ shard2
>    [junit4]   2> 2083675 T4336 oascc.SolrZkClient.makePath makePath:
> /collections/collection1/leaders/shard2
>    [junit4]   2> 2084563 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2084584 T4299 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> 2084584 T4306 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> 2084584 T4335 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> 2084586 T4319 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> 2084634 T4336 oasc.ZkController.register We are
> http://127.0.0.1:50299/collection1/ and leader is
> http://127.0.0.1:50299/collection1/
>    [junit4]   2> 2084634 T4336 oasc.ZkController.checkRecovery I am the
> leader, no recovery necessary
>    [junit4]   2> 2084635 T4336 oasc.ZkController.publish publishing
> core=collection1 state=active
>    [junit4]   2> 2084635 T4336 oasc.ZkController.publish numShards not
> found on descriptor - reading it from system property
>    [junit4]   2> 2084639 T4336 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2084642 T4277 oass.SolrDispatchFilter.init
> user.dir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
>    [junit4]   2> 2084643 T4277 oass.SolrDispatchFilter.init
> SolrDispatchFilter.init() done
>    [junit4]   2> 2084644 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
>    [junit4]   2> 2085150 T4277 oejs.Server.doStart jetty-8.1.10.v20130312
>    [junit4]   2> 2085156 T4277 oejs.AbstractConnector.doStart Started
> SelectChannelConnector@127.0.0.1:50302
>    [junit4]   2> 2085158 T4277 oass.SolrDispatchFilter.init
> SolrDispatchFilter.init()
>    [junit4]   2> 2085159 T4277 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
>    [junit4]   2> 2085160 T4277 oasc.SolrResourceLoader.locateSolrHome
> using system property solr.solr.home:
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty3-1373634262951
>    [junit4]   2> 2085160 T4277 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory:
> '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty3-1373634262951/'
>    [junit4]   2> 2085211 T4277 oasc.ConfigSolr.fromFile Loading container
> configuration from
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty3-1373634262951/solr.xml
>    [junit4]   2> 2085336 T4277 oasc.CoreContainer.<init> New CoreContainer
> 625648838
>    [junit4]   2> 2085337 T4277 oasc.CoreContainer.load Loading cores into
> CoreContainer
> [instanceDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty3-1373634262951/]
>    [junit4]   2> 2085344 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 120000
>    [junit4]   2> 2085345 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
>    [junit4]   2> 2085347 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 15000
>    [junit4]   2> 2085347 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
>    [junit4]   2> 2085348 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
>    [junit4]   2> 2085348 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
>    [junit4]   2> 2085349 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
>    [junit4]   2> 2085349 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
>    [junit4]   2> 2085350 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
>    [junit4]   2> 2085350 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
>    [junit4]   2> 2085373 T4277 oasl.LogWatcher.newRegisteredLogWatcher
> Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
>    [junit4]   2> 2085374 T4277 oasc.ZkContainer.initZooKeeper Zookeeper
> client=127.0.0.1:50289/solr
>    [junit4]   2> 2085375 T4277 oasc.ZkController.checkChrootPath zkHost
> includes chroot
>    [junit4]   2> 2085377 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2085383 T4349 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@68b2327dname:ZooKeeperConnection Watcher:
> 127.0.0.1:50289 got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2085384 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2085391 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
>    [junit4]   2> 2085398 T4279 oazs.NIOServerCnxn.doIO WARN caught end of
> stream exception EndOfStreamException: Unable to read additional data from
> client sessionid 0x13fd2fb31680009, likely client has closed socket
>    [junit4]   2>        at
> org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
>    [junit4]   2>        at
> org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
>    [junit4]   2>        at java.lang.Thread.run(Thread.java:724)
>    [junit4]   2>
>    [junit4]   2> 2085416 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2085423 T4351 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@20fcb50fname:ZooKeeperConnection Watcher:
> 127.0.0.1:50289/solr got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2085423 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2085435 T4277
> oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster
> state from ZooKeeper...
>    [junit4]   2> 2086106 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2086111 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2
> message={
>    [junit4]   2>          "operation":"state",
>    [junit4]   2>          "core_node_name":"core_node2",
>    [junit4]   2>          "numShards":"2",
>    [junit4]   2>          "shard":"shard2",
>    [junit4]   2>          "roles":null,
>    [junit4]   2>          "state":"active",
>    [junit4]   2>          "shard_state":"active",
>    [junit4]   2>          "core":"collection1",
>    [junit4]   2>          "collection":"collection1",
>    [junit4]   2>          "shard_range":null,
>    [junit4]   2>          "node_name":"127.0.0.1:50299_",
>    [junit4]   2>          "base_url":"http://127.0.0.1:50299"}
>    [junit4]   2> 2086125 T4306 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> 2086126 T4351 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> 2086135 T4299 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> 2086140 T4319 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> 2086145 T4335 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> 2086445 T4277 oasc.ZkController.createEphemeralLiveNode
> Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50302_
>    [junit4]   2> 2086448 T4277 oascc.SolrZkClient.makePath makePath:
> /live_nodes/127.0.0.1:50302_
>    [junit4]   2> 2086457 T4351 oascc.ZkStateReader$3.process Updating live
> nodes... (4)
>    [junit4]   2> 2086457 T4351 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 4)
>    [junit4]   2> 2086458 T4299 oascc.ZkStateReader$3.process Updating live
> nodes... (4)
>    [junit4]   2> 2086459 T4299 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 4)
>    [junit4]   2> 2086465 T4319 oascc.ZkStateReader$3.process Updating live
> nodes... (4)
>    [junit4]   2> 2086466 T4319 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 4)
>    [junit4]   2> 2086476 T4306 oascc.ZkStateReader$3.process Updating live
> nodes... (4)
>    [junit4]   2> 2086477 T4306 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 4)
>    [junit4]   2> 2086481 T4335 oascc.ZkStateReader$3.process Updating live
> nodes... (4)
>    [junit4]   2> 2086482 T4335 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 4)
>    [junit4]   2> 2086497 T4352 oasc.ZkController.publish publishing
> core=collection1 state=down
>    [junit4]   2> 2086497 T4352 oasc.ZkController.publish numShards not
> found on descriptor - reading it from system property
>    [junit4]   2> 2086500 T4352 oasc.ZkController.waitForCoreNodeName look
> for our core node name
>    [junit4]   2> 2087661 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2087664 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2
> message={
>    [junit4]   2>          "operation":"state",
>    [junit4]   2>          "core_node_name":"core_node3",
>    [junit4]   2>          "numShards":"2",
>    [junit4]   2>          "shard":null,
>    [junit4]   2>          "roles":null,
>    [junit4]   2>          "state":"down",
>    [junit4]   2>          "shard_state":"active",
>    [junit4]   2>          "core":"collection1",
>    [junit4]   2>          "collection":"collection1",
>    [junit4]   2>          "shard_range":null,
>    [junit4]   2>          "node_name":"127.0.0.1:50302_",
>    [junit4]   2>          "base_url":"http://127.0.0.1:50302"}
>    [junit4]   2> 2087665 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Collection already exists
> with numShards=2
>    [junit4]   2> 2087665 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard
> shard=shard1
>    [junit4]   2> 2087676 T4299 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> 2087682 T4306 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> 2087683 T4351 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> 2087682 T4335 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> 2087682 T4319 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> 2088507 T4352 oasc.ZkController.waitForShardId waiting to
> find shard id in clusterstate for collection1
>    [junit4]   2> 2088508 T4352 oasc.CoreContainer.create Creating SolrCore
> 'collection1' using instanceDir:
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty3-1373634262951/collection1
>    [junit4]   2> 2088508 T4352 oasc.ZkController.createCollectionZkNode
> Check for collection zkNode:collection1
>    [junit4]   2> 2088510 T4352 oasc.ZkController.createCollectionZkNode
> Collection zkNode exists
>    [junit4]   2> 2088511 T4352 oasc.ZkController.readConfigName Load
> collection config from:/collections/collection1
>    [junit4]   2> 2088514 T4352 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory:
> '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty3-1373634262951/collection1/'
>    [junit4]   2> 2088515 T4352 oasc.SolrResourceLoader.replaceClassLoader
> Adding
> 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty3-1373634262951/collection1/lib/classes/'
> to classloader
>    [junit4]   2> 2088516 T4352 oasc.SolrResourceLoader.replaceClassLoader
> Adding
> 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty3-1373634262951/collection1/lib/README'
> to classloader
>    [junit4]   2> 2088600 T4352 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_50
>    [junit4]   2> 2088700 T4352 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig.xml
>    [junit4]   2> 2088703 T4352 oass.IndexSchema.readSchema Reading Solr
> Schema from schema.xml
>    [junit4]   2> 2088718 T4352 oass.IndexSchema.readSchema [collection1]
> Schema name=test
>    [junit4]   2> 2089637 T4352 oass.IndexSchema.readSchema default search
> field in schema is text
>    [junit4]   2> 2089641 T4352 oass.IndexSchema.readSchema unique key
> field: id
>    [junit4]   2> 2089644 T4352 oass.FileExchangeRateProvider.reload
> Reloading exchange rates from file currency.xml
>    [junit4]   2> 2089652 T4352 oass.FileExchangeRateProvider.reload
> Reloading exchange rates from file currency.xml
>    [junit4]   2> 2089731 T4352 oasc.SolrCore.initDirectoryFactory
> org.apache.solr.core.MockDirectoryFactory
>    [junit4]   2> 2089732 T4352 oasc.SolrCore.<init> [collection1] Opening
> new SolrCore at
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty3-1373634262951/collection1/,
> dataDir=./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty3/
>    [junit4]   2> 2089732 T4352 oasc.JmxMonitoredMap.<init> JMX monitoring
> is enabled. Adding Solr mbeans to JMX Server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@9df4f6e
>    [junit4]   2> 2089733 T4352 oasc.CachingDirectoryFactory.get return new
> directory for ./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty3
>    [junit4]   2> 2089734 T4352 oasc.SolrCore.getNewIndexDir New index
> directory detected: old=null
> new=./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty3/index/
>    [junit4]   2> 2089734 T4352 oasc.SolrCore.initIndex WARN [collection1]
> Solr index directory
> './org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty3/index' doesn't
> exist. Creating new index...
>    [junit4]   2> 2089736 T4352 oasc.CachingDirectoryFactory.get return new
> directory for
> ./org.apache.solr.cloud.ShardSplitTest-1373634244959/jetty3/index
>    [junit4]   2> 2089741 T4352 oasc.SolrDeletionPolicy.onCommit
> SolrDeletionPolicy.onCommit: commits: num=1
>    [junit4]   2>
>  commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4602738lockFactory=org.apache.lucene.store.NativeFSLockFactory@5c314342
> ),segFN=segments_1,generation=1}
>    [junit4]   2> 2089742 T4352 oasc.SolrDeletionPolicy.updateCommits
> newest commit generation = 1
>    [junit4]   2> 2089751 T4352 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "dedupe"
>    [junit4]   2> 2089751 T4352 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "stored_sig"
>    [junit4]   2> 2089753 T4352 oasup.UpdateRequestProcessorChain.init
> inserting DistributedUpdateProcessorFactory into
> updateRequestProcessorChain "distrib-dup-test-chain-implicit"
>    [junit4]   2> 2089754 T4352 oasc.RequestHandlers.initHandlersFromConfig
> created standard: solr.StandardRequestHandler
>    [junit4]   2> 2089755 T4352 oasc.RequestHandlers.initHandlersFromConfig
> created /get: solr.RealTimeGetHandler
>    [junit4]   2> 2089755 T4352 oasc.RequestHandlers.initHandlersFromConfig
> adding lazy requestHandler: solr.ReplicationHandler
>    [junit4]   2> 2089755 T4352 oasc.RequestHandlers.initHandlersFromConfig
> created /replication: solr.ReplicationHandler
>    [junit4]   2> 2089756 T4352 oasc.RequestHandlers.initHandlersFromConfig
> created /update: solr.UpdateRequestHandler
>    [junit4]   2> 2089757 T4352 oasc.RequestHandlers.initHandlersFromConfig
> created /admin/: org.apache.solr.handler.admin.AdminHandlers
>    [junit4]   2> 2089773 T4352 oashl.XMLLoader.init
> xsltCacheLifetimeSeconds=60
>    [junit4]   2> 2089789 T4352 oass.SolrIndexSearcher.<init> Opening
> Searcher@5c16b600 main
>    [junit4]   2> 2089791 T4352 oasu.CommitTracker.<init> Hard AutoCommit:
> disabled
>    [junit4]   2> 2089791 T4352 oasu.CommitTracker.<init> Soft AutoCommit:
> disabled
>    [junit4]   2> 2089798 T4353 oasc.SolrCore.registerSearcher
> [collection1] Registered new searcher Searcher@5c16b600main{StandardDirectoryReader(segments_1:1)}
>    [junit4]   2> 2089801 T4352 oasc.CoreContainer.registerCore registering
> core: collection1
>    [junit4]   2> 2089802 T4352 oasc.ZkController.register Register replica
> - core:collection1 address:http://127.0.0.1:50302 collection:collection1
> shard:shard1
>    [junit4]   2> 2089802 T4352 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
>    [junit4]   2> 2089829 T4352 oasc.ZkController.register We are
> http://127.0.0.1:50302/collection1/ and leader is
> http://127.0.0.1:50296/collection1/
>    [junit4]   2> 2089830 T4352 oasc.ZkController.register No LogReplay
> needed for core=collection1 baseURL=http://127.0.0.1:50302
>    [junit4]   2> 2089830 T4352 oasc.ZkController.checkRecovery Core needs
> to recover:collection1
>    [junit4]   2> 2089830 T4352 oasu.DefaultSolrCoreState.doRecovery
> Running recovery - first canceling any ongoing recovery
>    [junit4]   2> ASYNC  NEW_CORE C4550 name=collection1
> org.apache.solr.core.SolrCore@75fdf54c url=
> http://127.0.0.1:50302/collection1 node=127.0.0.1:50302_
> C4550_STATE=coll:collection1 core:collection1 props:{state=down,
> core=collection1, node_name=127.0.0.1:50302_, base_url=
> http://127.0.0.1:50302}
>    [junit4]   2> 2089832 T4354 C4550 P50302 oasc.RecoveryStrategy.run
> Starting recovery process.  core=collection1 recoveringAfterStartup=true
>    [junit4]   2> 2089834 T4354 C4550 P50302
> oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
>    [junit4]   2> 2089834 T4354 C4550 P50302 oasc.ZkController.publish
> publishing core=collection1 state=recovering
>    [junit4]   2> 2089835 T4354 C4550 P50302 oasc.ZkController.publish
> numShards not found on descriptor - reading it from system property
>    [junit4]   2> 2089837 T4352 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2089839 T4354 C4550 P50302
> oascsi.HttpClientUtil.createClient Creating new http client,
> config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
>    [junit4]   2> 2089852 T4277 oass.SolrDispatchFilter.init
> user.dir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
>    [junit4]   2> 2089853 T4277 oass.SolrDispatchFilter.init
> SolrDispatchFilter.init() done
>    [junit4]   2> 2089853 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
>    [junit4]   2> 2089863 T4315
> oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for
> coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader:
> true
>    [junit4]   2> 2090305 T4277 oejs.Server.doStart jetty-8.1.10.v20130312
>    [junit4]   2> 2090312 T4277 oejs.AbstractConnector.doStart Started
> SelectChannelConnector@127.0.0.1:50306
>    [junit4]   2> 2090320 T4277 oass.SolrDispatchFilter.init
> SolrDispatchFilter.init()
>    [junit4]   2> 2090321 T4277 oasc.SolrResourceLoader.locateSolrHome JNDI
> not configured for solr (NoInitialContextEx)
>    [junit4]   2> 2090321 T4277 oasc.SolrResourceLoader.locateSolrHome
> using system property solr.solr.home:
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty4-1373634268161
>    [junit4]   2> 2090322 T4277 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory:
> '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty4-1373634268161/'
>    [junit4]   2> 2090368 T4277 oasc.ConfigSolr.fromFile Loading container
> configuration from
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty4-1373634268161/solr.xml
>    [junit4]   2> 2090529 T4277 oasc.CoreContainer.<init> New CoreContainer
> 589690059
>    [junit4]   2> 2090530 T4277 oasc.CoreContainer.load Loading cores into
> CoreContainer
> [instanceDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty4-1373634268161/]
>    [junit4]   2> 2090532 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting socketTimeout to: 120000
>    [junit4]   2> 2090532 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting urlScheme to: http://
>    [junit4]   2> 2090533 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting connTimeout to: 15000
>    [junit4]   2> 2090533 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maxConnectionsPerHost to: 20
>    [junit4]   2> 2090533 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting corePoolSize to: 0
>    [junit4]   2> 2090534 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maximumPoolSize to: 2147483647
>    [junit4]   2> 2090535 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting maxThreadIdleTime to: 5
>    [junit4]   2> 2090535 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting sizeOfQueue to: -1
>    [junit4]   2> 2090535 T4277 oashc.HttpShardHandlerFactory.getParameter
> Setting fairnessPolicy to: false
>    [junit4]   2> 2090536 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
>    [junit4]   2> 2090554 T4277 oasl.LogWatcher.newRegisteredLogWatcher
> Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
>    [junit4]   2> 2090556 T4277 oasc.ZkContainer.initZooKeeper Zookeeper
> client=127.0.0.1:50289/solr
>    [junit4]   2> 2090556 T4277 oasc.ZkController.checkChrootPath zkHost
> includes chroot
>    [junit4]   2> 2090563 T4366 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@350cb66name:ZooKeeperConnection Watcher:
> 127.0.0.1:50289 got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2090564 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2090564 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2090571 T4277 oascsi.HttpClientUtil.createClient Creating
> new http client,
> config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
>    [junit4]   2> 2090575 T4279 oazs.NIOServerCnxn.doIO WARN caught end of
> stream exception EndOfStreamException: Unable to read additional data from
> client sessionid 0x13fd2fb3168000b, likely client has closed socket
>    [junit4]   2>        at
> org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
>    [junit4]   2>        at
> org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
>    [junit4]   2>        at java.lang.Thread.run(Thread.java:724)
>    [junit4]   2>
>    [junit4]   2> 2090596 T4277 oascc.ConnectionManager.waitForConnected
> Waiting for client to connect to ZooKeeper
>    [junit4]   2> 2090601 T4368 oascc.ConnectionManager.process Watcher
> org.apache.solr.common.cloud.ConnectionManager@70c61112name:ZooKeeperConnection Watcher:
> 127.0.0.1:50289/solr got event WatchedEvent state:SyncConnected type:None
> path:null path:null type:None
>    [junit4]   2> 2090602 T4277 oascc.ConnectionManager.waitForConnected
> Client is connected to ZooKeeper
>    [junit4]   2> 2090618 T4277
> oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster
> state from ZooKeeper...
>    [junit4]   2> 2090727 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2090730 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2
> message={
>    [junit4]   2>          "operation":"state",
>    [junit4]   2>          "core_node_name":"core_node3",
>    [junit4]   2>          "numShards":"2",
>    [junit4]   2>          "shard":"shard1",
>    [junit4]   2>          "roles":null,
>    [junit4]   2>          "state":"recovering",
>    [junit4]   2>          "shard_state":"active",
>    [junit4]   2>          "core":"collection1",
>    [junit4]   2>          "collection":"collection1",
>    [junit4]   2>          "shard_range":null,
>    [junit4]   2>          "node_name":"127.0.0.1:50302_",
>    [junit4]   2>          "base_url":"http://127.0.0.1:50302"}
>    [junit4]   2> 2090746 T4319 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> 2090747 T4368 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> 2090748 T4335 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> 2090748 T4299 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> 2090749 T4306 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> 2090749 T4351 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> 2090867 T4315
> oasha.CoreAdminHandler.handleWaitForStateAction Waited coreNodeName:
> core_node3, state: recovering, checkLive: true, onlyIfLeader: true for: 1
> seconds.
>    [junit4]   2> 2090868 T4315 oass.SolrDispatchFilter.handleAdminRequest
> [admin] webapp=null path=/admin/cores
> params={coreNodeName=core_node3&state=recovering&nodeName=127.0.0.1:50302_&action=PREPRECOVERY&checkLive=true&core=collection1&wt=javabin&onlyIfLeader=true&version=2}
> status=0 QTime=1005
>    [junit4]   2> 2091630 T4277 oasc.ZkController.createEphemeralLiveNode
> Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50306_
>    [junit4]   2> 2091634 T4277 oascc.SolrZkClient.makePath makePath:
> /live_nodes/127.0.0.1:50306_
>    [junit4]   2> 2091644 T4319 oascc.ZkStateReader$3.process Updating live
> nodes... (5)
>    [junit4]   2> 2091645 T4319 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 5)
>    [junit4]   2> 2091645 T4306 oascc.ZkStateReader$3.process Updating live
> nodes... (5)
>    [junit4]   2> 2091646 T4306 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 5)
>    [junit4]   2> 2091646 T4335 oascc.ZkStateReader$3.process Updating live
> nodes... (5)
>    [junit4]   2> 2091646 T4335 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 5)
>    [junit4]   2> 2091651 T4368 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 4)
>    [junit4]   2> 2091660 T4299 oascc.ZkStateReader$3.process Updating live
> nodes... (5)
>    [junit4]   2> 2091661 T4299 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 5)
>    [junit4]   2> 2091660 T4351 oascc.ZkStateReader$3.process Updating live
> nodes... (5)
>    [junit4]   2> 2091661 T4351 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/live_nodes, has occurred - updating... (live nodes size: 5)
>    [junit4]   2> 2091664 T4368 oascc.ZkStateReader$3.process Updating live
> nodes... (5)
>    [junit4]   2> 2091677 T4369 oasc.ZkController.publish publishing
> core=collection1 state=down
>    [junit4]   2> 2091678 T4369 oasc.ZkController.publish numShards not
> found on descriptor - reading it from system property
>    [junit4]   2> 2091680 T4369 oasc.ZkController.waitForCoreNodeName look
> for our core node name
>    [junit4]   2> 2092276 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2092279 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2
> message={
>    [junit4]   2>          "operation":"state",
>    [junit4]   2>          "core_node_name":"core_node4",
>    [junit4]   2>          "numShards":"2",
>    [junit4]   2>          "shard":null,
>    [junit4]   2>          "roles":null,
>    [junit4]   2>          "state":"down",
>    [junit4]   2>          "shard_state":"active",
>    [junit4]   2>          "core":"collection1",
>    [junit4]   2>          "collection":"collection1",
>    [junit4]   2>          "shard_range":null,
>    [junit4]   2>          "node_name":"127.0.0.1:50306_",
>    [junit4]   2>          "base_url":"http://127.0.0.1:50306"}
>    [junit4]   2> 2092280 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Collection already exists
> with numShards=2
>    [junit4]   2> 2092280 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard
> shard=shard2
>    [junit4]   2> 2092289 T4335 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeDataChanged
> path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
>    [junit4]   2> 2092290 T4368 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeDataChanged
> path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
>    [junit4]   2> 2092295 T4299 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeDataChanged
> path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
>    [junit4]   2> 2092303 T4319 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeDataChanged
> path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
>    [junit4]   2> 2092306 T4351 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeDataChanged
> path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
>    [junit4]   2> 2092309 T4306 oascc.ZkStateReader$2.process A cluster
> state change: WatchedEvent state:SyncConnected type:NodeDataChanged
> path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
>    [junit4]   2> 2092682 T4369 oasc.ZkController.waitForShardId waiting to
> find shard id in clusterstate for collection1
>    [junit4]   2> 2092683 T4369 oasc.CoreContainer.create Creating SolrCore
> 'collection1' using instanceDir:
> /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty4-1373634268161/collection1
>    [junit4]   2> 2092683 T4369 oasc.ZkController.createCollectionZkNode
> Check for collection zkNode:collection1
>    [junit4]   2> 2092685 T4369 oasc.ZkController.createCollectionZkNode
> Collection zkNode exists
>    [junit4]   2> 2092685 T4369 oasc.ZkController.readConfigName Load
> collection config from:/collections/collection1
>    [junit4]   2> 2092688 T4369 oasc.SolrResourceLoader.<init> new
> SolrResourceLoader for directory:
> '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty4-1373634268161/collection1/'
>    [junit4]   2> 2092689 T4369 oasc.SolrResourceLoader.replaceClassLoader
> Adding
> 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty4-1373634268161/collection1/lib/classes/'
> to classloader
>    [junit4]   2> 2092690 T4369 oasc.SolrResourceLoader.replaceClassLoader
> Adding
> 'file:/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty4-1373634268161/collection1/lib/README'
> to classloader
>    [junit4]   2> 2092773 T4369 oasc.SolrConfig.<init> Using Lucene
> MatchVersion: LUCENE_50
>    [junit4]   2> ASYNC  NEW_CORE C4551 name=collection1
> org.apache.solr.core.SolrCore@75fdf54c url=
> http://127.0.0.1:50302/collection1 node=127.0.0.1:50302_
> C4551_STATE=coll:collection1 core:collection1 props:{state=recovering,
> core=collection1, node_name=127.0.0.1:50302_, base_url=
> http://127.0.0.1:50302}
>    [junit4]   2> 2092870 T4354 C4551 P50302
> oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from
> http://127.0.0.1:50296/collection1/ core=collection1 -
> recoveringAfterStartup=true
>    [junit4]   2> 2092871 T4354 C4551 P50302 oasu.PeerSync.sync PeerSync:
> core=collection1 url=http://127.0.0.1:50302 START replicas=[
> http://127.0.0.1:50296/collection1/] nUpdates=100
>    [junit4]   2> 2092871 T4354 C4551 P50302 oasu.PeerSync.sync WARN no
> frame of reference to tell of we've missed updates
>    [junit4]   2> 2092873 T4354 C4551 P50302
> oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful -
> trying replication. core=collection1
>    [junit4]   2> 2092873 T4354 C4551 P50302
> oasc.RecoveryStrategy.doRecovery Starting Replication Recovery.
> core=collection1
>    [junit4]   2> 2092873 T4354 C4551 P50302
> oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
>    [junit4]   2> 2092874 T4354 C4551 P50302 oasu.UpdateLog.bufferUpdates
> Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
>    [junit4]   2> 2092874 T4354 C4551 P50302
> oasc.RecoveryStrategy.replicate Attempting to replicate from
> http://127.0.0.1:50296/collection1/. core=collection1
>    [junit4]   2> 2092874 T4354 C4551 P50302
> oascsi.HttpClientUtil.createClient Creating new http client,
> config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
>    [junit4]   2> ASYNC  NEW_CORE C4552 name=collection1
> org.apache.solr.core.SolrCore@47cf994c url=
> http://127.0.0.1:50296/collection1 node=127.0.0.1:50296_
> C4552_STATE=coll:collection1 core:collection1 props:{state=active,
> core=collection1, node_name=127.0.0.1:50296_, base_url=
> http://127.0.0.1:50296, leader=true}
>    [junit4]   2> 2092929 T4315 C4552 P50296 oasc.SolrCore.execute
> [collection1] webapp= path=/get
> params={getVersions=100&distrib=false&wt=javabin&qt=/get&version=2}
> status=0 QTime=10
>    [junit4]   2> 2092956 T4369 oasc.SolrConfig.<init> Loaded SolrConfig:
> solrconfig.xml
>    [junit4]   2> 2092957 T4313 C4552 P50296
> oasu.DirectUpdateHandler2.commit start
> commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
>    [junit4]   2> 2092962 T4369 oass.IndexSchema.readSchema Reading Solr
> Schema from schema.xml
>    [junit4]   2> 2092969 T4313 C4552 P50296 oasc.SolrDeletionPolicy.onInit
> SolrDeletionPolicy.onInit: commits: num=1
>    [junit4]   2>
>  commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@80e598flockFactory=org.apache.lucene.store.NativeFSLockFactory@23a550c7
> ),segFN=segments_1,generation=1}
>    [junit4]   2> 2092972 T4313 C4552 P50296
> oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
>    [junit4]   2> 2092973 T4313 C4552 P50296
> oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
>    [junit4]   2> 2092976 T4313 C4552 P50296 oass.SolrIndexSearcher.<init>
> Opening Searcher@16df86a6 realtime
>    [junit4]   2> 2092976 T4313 C4552 P50296
> oasu.DirectUpdateHandler2.commit end_commit_flush
>    [junit4]   2> 2092977 T4313 C4552 P50296
> oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update
> params={waitSearcher=true&openSearcher=false&commit=true&wt=javabin&commit_end_point=true&version=2&softCommit=false}
> {commit=} 0 20
>    [junit4]   2> 2092982 T4354 C4551 P50302 oash.ReplicationHandler.inform
> Commits will be reserved for  10000
>    [junit4]   2> 2092982 T4354 C4551 P50302 oash.SnapPuller.<init>  No
> value set for 'pollInterval'. Timer Task not started.
>    [junit4]   2> 2092993 T4311 C4552 P50296 oash.ReplicationHandler.inform
> Commits will be reserved for  10000
>    [junit4]   2> 2092994 T4311 C4552 P50296 oasc.SolrCore.execute
> [collection1] webapp= path=/replication
> params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0
> QTime=2
>    [junit4]   2> 2092998 T4369 oass.IndexSchema.readSchema [collection1]
> Schema name=test
>    [junit4]   2> 2093002 T4354 C4551 P50302 oasc.RecoveryStrategy.replay
> No replay needed. core=collection1
>    [junit4]   2> 2093002 T4354 C4551 P50302
> oasc.RecoveryStrategy.doRecovery Replication Recovery was successful -
> registering as Active. core=collection1
>    [junit4]   2> 2093003 T4354 C4551 P50302 oasc.ZkController.publish
> publishing core=collection1 state=active
>    [junit4]   2> 2093003 T4354 C4551 P50302 oasc.ZkController.publish
> numShards not found on descriptor - reading it from system property
>    [junit4]   2> 2093006 T4354 C4551 P50302
> oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
>    [junit4]   2> 2093807 T4300 oascc.ZkStateReader.updateClusterState
> Updating cloud state from ZooKeeper...
>    [junit4]   2> 2093852 T4300
> oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2
> message={
>    [junit4]   2>          "operation":"state",
>    [junit4]   2>          "core_node_name":"core_node3",
>    [junit4]   2>          "numShards":"2",
>    [junit4]   2>          "shard":"shard1",
>    [junit4]   2>          "roles":null,
>    [junit4]   2>          "state":"active",
>    [junit4]   2>          "shard_state":"active",
>    [junit4]   2>          "core":"collection1",
>    [junit4]   2>          "collection":"collection1",
>    [junit4]   2>          "shard_range":null,
>    [junit4]   2>          "node_name":"127.0.0.1:50302_",
>    [junit4]   2>          "base_url":"http://127.0.0.1:50302"}
>    [junit4]   2> 2093862 T4319 oascc.ZkStateReader$2.process A cluster sta
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org
> ...

Mime
View raw message