You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/10/25 08:27:02 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.7.0) - Build # 945 -
Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/945/
Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.cloud.ShardSplitTest.testDistribSearch
Error Message:
Wrong doc count on shard1_0 expected:<130> but was:<82>
Stack Trace:
java.lang.AssertionError: Wrong doc count on shard1_0 expected:<130> but was:<82>
at __randomizedtesting.SeedInfo.seed([26991E9917156F3:838F1FF1E62E36CF]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:477)
at org.apache.solr.cloud.ShardSplitTest.splitByUniqueKeyTest(ShardSplitTest.java:249)
at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:113)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.GeneratedMethodAccessor58.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:744)
Build Log:
[...truncated 18720 lines...]
[junit4] Suite: org.apache.solr.cloud.ShardSplitTest
[junit4] 2> 2694247 T7485 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /uhp/l
[junit4] 2> 2694255 T7485 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./solrtest-ShardSplitTest-1382682116560
[junit4] 2> 2694257 T7485 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 2694259 T7486 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 2694360 T7485 oasc.ZkTestServer.run start zk server on port:51436
[junit4] 2> 2694361 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2694369 T7492 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@45d2fc5a name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694369 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2694370 T7485 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2694379 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2694384 T7494 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@285bf4e name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2694384 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2694385 T7485 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 2694393 T7485 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 2694399 T7485 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 2694406 T7485 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 2694413 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2694414 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2694425 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 2694425 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 2694432 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2694433 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2694440 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2694441 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2694448 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2694449 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 2694455 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2694458 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 2694467 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2694468 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2694480 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2694480 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2694492 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2694493 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2694507 T7485 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2694508 T7485 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2694876 T7485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2694886 T7485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51439
[junit4] 2> 2694886 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2694887 T7485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2694887 T7485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822
[junit4] 2> 2694887 T7485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/'
[junit4] 2> 2694927 T7485 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/solr.xml
[junit4] 2> 2695018 T7485 oasc.CoreContainer.<init> New CoreContainer 311952099
[junit4] 2> 2695019 T7485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/]
[junit4] 2> 2695020 T7485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2695020 T7485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2695021 T7485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2695021 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2695022 T7485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2695022 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2695022 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2695023 T7485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2695023 T7485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2695028 T7485 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2695029 T7485 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2695029 T7485 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51436/solr
[junit4] 2> 2695029 T7485 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2695031 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2695041 T7505 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@231ca705 name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2695041 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2695046 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2695050 T7507 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@753ff1ab name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2695051 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2695057 T7485 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 2695068 T7485 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 2695077 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2695082 T7485 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51439_uhp%2Fl
[junit4] 2> 2695084 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51439_uhp%2Fl
[junit4] 2> 2695094 T7485 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 2695105 T7485 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 2695114 T7485 oasc.Overseer.start Overseer (id=90615455191400451-127.0.0.1:51439_uhp%2Fl-n_0000000000) starting
[junit4] 2> 2695129 T7485 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 2695144 T7509 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 2695145 T7485 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 2695152 T7485 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 2695163 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2695178 T7508 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 2695194 T7510 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2695194 T7510 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2695200 T7510 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2696698 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2696699 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51439/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51439_uhp%2Fl",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2696700 T7508 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 2696700 T7508 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2696713 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2697206 T7510 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2697206 T7510 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/collection1
[junit4] 2> 2697206 T7510 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 2697208 T7510 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2697208 T7510 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 2697211 T7510 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/collection1/'
[junit4] 2> 2697213 T7510 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/collection1/lib/classes/' to classloader
[junit4] 2> 2697214 T7510 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/collection1/lib/README' to classloader
[junit4] 2> 2697299 T7510 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2697377 T7510 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2697381 T7510 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2697393 T7510 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2698179 T7510 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2698183 T7510 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2698189 T7510 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2698201 T7510 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2698273 T7510 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2698274 T7510 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ShardSplitTest-controljetty-1382682116822/collection1/, dataDir=./org.apache.solr.cloud.ShardSplitTest-1382682116559/control/data/
[junit4] 2> 2698274 T7510 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d4d87e
[junit4] 2> 2698274 T7510 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/control/data
[junit4] 2> 2698275 T7510 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ShardSplitTest-1382682116559/control/data/index/
[junit4] 2> 2698275 T7510 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ShardSplitTest-1382682116559/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 2698275 T7510 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/control/data/index
[junit4] 2> 2698276 T7510 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=42.181640625, floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=28.419714701414733, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2698276 T7510 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@60a87148 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e656e56),segFN=segments_1,generation=1}
[junit4] 2> 2698276 T7510 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2698279 T7510 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2698280 T7510 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2698280 T7510 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2698280 T7510 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2698281 T7510 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2698281 T7510 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2698282 T7510 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2698283 T7510 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2698283 T7510 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2698284 T7510 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2698285 T7510 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2698285 T7510 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2698285 T7510 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2698286 T7510 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2698287 T7510 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2698298 T7510 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2698304 T7510 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2698305 T7510 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2698306 T7510 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10609207416541731]
[junit4] 2> 2698306 T7510 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@60a87148 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5e656e56),segFN=segments_1,generation=1}
[junit4] 2> 2698306 T7510 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2698307 T7510 oass.SolrIndexSearcher.<init> Opening Searcher@23223163 main
[junit4] 2> 2698312 T7511 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@23223163 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2698312 T7510 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2698313 T7510 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51439/uhp/l collection:control_collection shard:shard1
[junit4] 2> 2698316 T7510 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 2698343 T7510 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2698350 T7510 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2698350 T7510 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 2698351 T7510 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51439/uhp/l/collection1/
[junit4] 2> 2698351 T7510 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2698351 T7510 oasc.SyncStrategy.syncToMe http://127.0.0.1:51439/uhp/l/collection1/ has no replicas
[junit4] 2> 2698352 T7510 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51439/uhp/l/collection1/ shard1
[junit4] 2> 2698352 T7510 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 2699731 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2699759 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2699790 T7510 oasc.ZkController.register We are http://127.0.0.1:51439/uhp/l/collection1/ and leader is http://127.0.0.1:51439/uhp/l/collection1/
[junit4] 2> 2699790 T7510 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51439/uhp/l
[junit4] 2> 2699790 T7510 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2699791 T7510 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2699791 T7510 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2699795 T7510 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2699798 T7485 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2699799 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2699801 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2699806 T7514 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2fd45c5a name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2699806 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2699810 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2699818 T7485 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4] 2> 2700170 T7485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2700175 T7485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51443
[junit4] 2> 2700178 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2700178 T7485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2700179 T7485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121
[junit4] 2> 2700179 T7485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/'
[junit4] 2> 2700223 T7485 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/solr.xml
[junit4] 2> 2700319 T7485 oasc.CoreContainer.<init> New CoreContainer 2132033048
[junit4] 2> 2700320 T7485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/]
[junit4] 2> 2700322 T7485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2700322 T7485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2700322 T7485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2700323 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2700323 T7485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2700324 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2700324 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2700324 T7485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2700325 T7485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2700330 T7485 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2700331 T7485 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2700332 T7485 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51436/solr
[junit4] 2> 2700332 T7485 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2700334 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2700340 T7525 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@150e4b76 name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2700340 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2700346 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2700350 T7527 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@53c0cfab name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2700351 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2700370 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2701282 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2701289 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:51439/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51439_uhp%2Fl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2701302 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2701302 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2701302 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2701384 T7485 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51443_uhp%2Fl
[junit4] 2> 2701387 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51443_uhp%2Fl
[junit4] 2> 2701393 T7527 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2701394 T7514 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2701395 T7507 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2701420 T7528 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2701421 T7528 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2701424 T7528 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2702815 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2702817 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51443/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51443_uhp%2Fl",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2702818 T7508 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 2702818 T7508 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2702830 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2702830 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2702830 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2703426 T7528 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2703426 T7528 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/collection1
[junit4] 2> 2703426 T7528 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2703428 T7528 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2703428 T7528 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2703432 T7528 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/collection1/'
[junit4] 2> 2703434 T7528 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/collection1/lib/classes/' to classloader
[junit4] 2> 2703435 T7528 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/collection1/lib/README' to classloader
[junit4] 2> 2703521 T7528 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2703579 T7528 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2703585 T7528 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2703598 T7528 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2704304 T7528 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2704307 T7528 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2704309 T7528 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2704319 T7528 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2704391 T7528 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2704391 T7528 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty1-1382682122121/collection1/, dataDir=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty1/
[junit4] 2> 2704391 T7528 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d4d87e
[junit4] 2> 2704392 T7528 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty1
[junit4] 2> 2704392 T7528 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty1/index/
[junit4] 2> 2704393 T7528 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 2704393 T7528 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty1/index
[junit4] 2> 2704393 T7528 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=42.181640625, floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=28.419714701414733, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2704394 T7528 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@569bfe84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b08b4c1),segFN=segments_1,generation=1}
[junit4] 2> 2704394 T7528 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2704399 T7528 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2704400 T7528 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2704401 T7528 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2704401 T7528 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2704402 T7528 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2704402 T7528 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2704402 T7528 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2704403 T7528 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2704403 T7528 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2704403 T7528 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2704404 T7528 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2704404 T7528 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2704404 T7528 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2704405 T7528 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2704405 T7528 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2704418 T7528 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2704424 T7528 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2704426 T7528 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2704426 T7528 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10609207416541731]
[junit4] 2> 2704427 T7528 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@569bfe84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b08b4c1),segFN=segments_1,generation=1}
[junit4] 2> 2704427 T7528 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2704427 T7528 oass.SolrIndexSearcher.<init> Opening Searcher@4047f15d main
[junit4] 2> 2704433 T7529 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4047f15d main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2704435 T7528 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2704435 T7528 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51443/uhp/l collection:collection1 shard:shard1
[junit4] 2> 2704438 T7528 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 2704467 T7528 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2704475 T7528 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2704476 T7528 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 2704476 T7528 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51443/uhp/l/collection1/
[junit4] 2> 2704476 T7528 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2704477 T7528 oasc.SyncStrategy.syncToMe http://127.0.0.1:51443/uhp/l/collection1/ has no replicas
[junit4] 2> 2704477 T7528 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51443/uhp/l/collection1/ shard1
[junit4] 2> 2704477 T7528 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 2705850 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2705874 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2705875 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2705874 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2705901 T7528 oasc.ZkController.register We are http://127.0.0.1:51443/uhp/l/collection1/ and leader is http://127.0.0.1:51443/uhp/l/collection1/
[junit4] 2> 2705902 T7528 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51443/uhp/l
[junit4] 2> 2705902 T7528 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2705903 T7528 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2705903 T7528 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2705906 T7528 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2705910 T7485 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2705910 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2706261 T7485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2706269 T7485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51446
[junit4] 2> 2706271 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2706271 T7485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2706272 T7485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215
[junit4] 2> 2706272 T7485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/'
[junit4] 2> 2706310 T7485 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/solr.xml
[junit4] 2> 2706399 T7485 oasc.CoreContainer.<init> New CoreContainer 1984235485
[junit4] 2> 2706399 T7485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/]
[junit4] 2> 2706401 T7485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2706402 T7485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2706402 T7485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2706402 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2706402 T7485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2706403 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2706403 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2706404 T7485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2706404 T7485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2706408 T7485 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2706408 T7485 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2706409 T7485 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51436/solr
[junit4] 2> 2706409 T7485 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2706411 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2706416 T7541 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a24a2a1 name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2706416 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2706423 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2706431 T7543 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@47de00aa name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2706433 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2706446 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2707394 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2707395 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:51443/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51443_uhp%2Fl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2707409 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2707410 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2707410 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2707410 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 2707456 T7485 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51446_uhp%2Fl
[junit4] 2> 2707459 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51446_uhp%2Fl
[junit4] 2> 2707467 T7527 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2707468 T7507 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2707468 T7543 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2707468 T7514 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2707487 T7544 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2707487 T7544 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2707490 T7544 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2708926 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2708929 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51446/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51446_uhp%2Fl",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 2708929 T7508 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 2708930 T7508 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 2708941 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2708942 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2708942 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2708942 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2709492 T7544 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2709493 T7544 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/collection1
[junit4] 2> 2709493 T7544 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2709495 T7544 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2709495 T7544 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2709498 T7544 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/collection1/'
[junit4] 2> 2709499 T7544 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/collection1/lib/classes/' to classloader
[junit4] 2> 2709500 T7544 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/collection1/lib/README' to classloader
[junit4] 2> 2709577 T7544 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2709643 T7544 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2709648 T7544 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2709662 T7544 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2710390 T7544 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2710395 T7544 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2710397 T7544 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2710406 T7544 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2710504 T7544 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2710505 T7544 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ShardSplitTest-jetty2-1382682128215/collection1/, dataDir=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty2/
[junit4] 2> 2710505 T7544 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d4d87e
[junit4] 2> 2710506 T7544 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty2
[junit4] 2> 2710506 T7544 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty2/index/
[junit4] 2> 2710506 T7544 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 2710507 T7544 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty2/index
[junit4] 2> 2710508 T7544 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=42.181640625, floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=28.419714701414733, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2710509 T7544 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6c2be92a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70a30453),segFN=segments_1,generation=1}
[junit4] 2> 2710509 T7544 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2710515 T7544 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2710516 T7544 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2710517 T7544 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2710517 T7544 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2710517 T7544 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2710518 T7544 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2710519 T7544 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2710519 T7544 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2710520 T7544 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2710521 T7544 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2710522 T7544 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2710522 T7544 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2710522 T7544 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2710523 T7544 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2710524 T7544 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2710534 T7544 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2710538 T7544 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2710539 T7544 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2710539 T7544 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10609207416541731]
[junit4] 2> 2710540 T7544 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6c2be92a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70a30453),segFN=segments_1,generation=1}
[junit4] 2> 2710540 T7544 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2710540 T7544 oass.SolrIndexSearcher.<init> Opening Searcher@217e56b5 main
[junit4] 2> 2710548 T7545 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@217e56b5 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2710552 T7544 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2710552 T7544 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51446/uhp/l collection:collection1 shard:shard2
[junit4] 2> 2710555 T7544 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 2710582 T7544 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 2710589 T7544 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2710589 T7544 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 2710590 T7544 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51446/uhp/l/collection1/
[junit4] 2> 2710590 T7544 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2710590 T7544 oasc.SyncStrategy.syncToMe http://127.0.0.1:51446/uhp/l/collection1/ has no replicas
[junit4] 2> 2710590 T7544 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51446/uhp/l/collection1/ shard2
[junit4] 2> 2710591 T7544 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 2711975 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2712002 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2712002 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2712002 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2712003 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2712050 T7544 oasc.ZkController.register We are http://127.0.0.1:51446/uhp/l/collection1/ and leader is http://127.0.0.1:51446/uhp/l/collection1/
[junit4] 2> 2712050 T7544 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51446/uhp/l
[junit4] 2> 2712051 T7544 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2712051 T7544 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 2712051 T7544 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2712056 T7544 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2712059 T7485 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2712060 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2712413 T7485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2712418 T7485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51449
[junit4] 2> 2712420 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2712420 T7485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2712420 T7485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364
[junit4] 2> 2712420 T7485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/'
[junit4] 2> 2712450 T7485 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/solr.xml
[junit4] 2> 2712544 T7485 oasc.CoreContainer.<init> New CoreContainer 638187818
[junit4] 2> 2712544 T7485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/]
[junit4] 2> 2712546 T7485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2712546 T7485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2712546 T7485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2712547 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2712547 T7485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2712547 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2712547 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2712548 T7485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2712548 T7485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2712552 T7485 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2712553 T7485 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2712553 T7485 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51436/solr
[junit4] 2> 2712553 T7485 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2712555 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2712560 T7557 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6ba07d19 name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2712561 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2712567 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2712572 T7559 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8d72211 name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2712573 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2712583 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2713523 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2713525 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:51446/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51446_uhp%2Fl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 2713540 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2713540 T7559 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2713541 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2713541 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2713541 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 2713598 T7485 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51449_uhp%2Fl
[junit4] 2> 2713602 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51449_uhp%2Fl
[junit4] 2> 2713608 T7559 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2713609 T7507 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2713610 T7543 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2713609 T7514 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2713610 T7527 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2713634 T7560 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2713634 T7560 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2713642 T7560 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2715054 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2715056 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:51449/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51449_uhp%2Fl",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node3"}
[junit4] 2> 2715056 T7508 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 2715057 T7508 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2715069 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2715070 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2715070 T7559 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2715069 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2715069 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2715645 T7560 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2715646 T7560 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/collection1
[junit4] 2> 2715646 T7560 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2715648 T7560 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2715649 T7560 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2715652 T7560 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/collection1/'
[junit4] 2> 2715653 T7560 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/collection1/lib/classes/' to classloader
[junit4] 2> 2715654 T7560 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/collection1/lib/README' to classloader
[junit4] 2> 2715733 T7560 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2715803 T7560 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2715807 T7560 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2715821 T7560 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2716525 T7560 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2716529 T7560 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2716531 T7560 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2716539 T7560 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2716615 T7560 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2716615 T7560 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ShardSplitTest-jetty3-1382682134364/collection1/, dataDir=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty3/
[junit4] 2> 2716616 T7560 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d4d87e
[junit4] 2> 2716617 T7560 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty3
[junit4] 2> 2716617 T7560 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty3/index/
[junit4] 2> 2716618 T7560 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty3/index' doesn't exist. Creating new index...
[junit4] 2> 2716618 T7560 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.ShardSplitTest-1382682116559/jetty3/index
[junit4] 2> 2716619 T7560 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=42.181640625, floorSegmentMB=2.158203125, forceMergeDeletesPctAllowed=28.419714701414733, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2716620 T7560 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3f7c7ce2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29f42af1),segFN=segments_1,generation=1}
[junit4] 2> 2716620 T7560 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2716627 T7560 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2716628 T7560 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2716628 T7560 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2716628 T7560 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2716629 T7560 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2716629 T7560 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2716630 T7560 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2716630 T7560 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2716630 T7560 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2716631 T7560 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2716632 T7560 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2716633 T7560 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2716633 T7560 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2716635 T7560 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2716636 T7560 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2716649 T7560 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2716655 T7560 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2716655 T7560 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2716657 T7560 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10609207416541731]
[junit4] 2> 2716658 T7560 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3f7c7ce2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29f42af1),segFN=segments_1,generation=1}
[junit4] 2> 2716658 T7560 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2716658 T7560 oass.SolrIndexSearcher.<init> Opening Searcher@dacb909 main
[junit4] 2> 2716663 T7561 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@dacb909 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2716663 T7560 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2716664 T7560 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51449/uhp/l collection:collection1 shard:shard1
[junit4] 2> 2716674 T7560 oasc.ZkController.register We are http://127.0.0.1:51449/uhp/l/collection1/ and leader is http://127.0.0.1:51443/uhp/l/collection1/
[junit4] 2> 2716674 T7560 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51449/uhp/l
[junit4] 2> 2716674 T7560 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 2716674 T7560 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C1386 name=collection1 org.apache.solr.core.SolrCore@ba5293 url=http://127.0.0.1:51449/uhp/l/collection1 node=127.0.0.1:51449_uhp%2Fl C1386_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:51449/uhp/l, core=collection1, node_name=127.0.0.1:51449_uhp%2Fl}
[junit4] 2> 2716676 T7562 C1386 P51449 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 2716676 T7562 C1386 P51449 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4] 2> 2716676 T7562 C1386 P51449 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4] 2> 2716676 T7562 C1386 P51449 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2716676 T7560 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2716680 T7485 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2716681 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2716684 T7523 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true
[junit4] 2> 2717064 T7485 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2717070 T7485 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51453
[junit4] 2> 2717074 T7485 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2717074 T7485 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2717075 T7485 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ShardSplitTest-jetty4-1382682138986
[junit4] 2> 2717075 T7485 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ShardSplitTest-jetty4-1382682138986/'
[junit4] 2> 2717115 T7485 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./org.apache.solr.cloud.ShardSplitTest-jetty4-1382682138986/solr.xml
[junit4] 2> 2717209 T7485 oasc.CoreContainer.<init> New CoreContainer 1967436185
[junit4] 2> 2717210 T7485 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ShardSplitTest-jetty4-1382682138986/]
[junit4] 2> 2717211 T7485 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2717212 T7485 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2717212 T7485 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2717212 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2717212 T7485 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2717213 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2717214 T7485 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2717214 T7485 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2717214 T7485 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2717219 T7485 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2717219 T7485 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2717220 T7485 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51436/solr
[junit4] 2> 2717220 T7485 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2717222 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2717231 T7574 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3b882275 name:ZooKeeperConnection Watcher:127.0.0.1:51436 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2717231 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2717237 T7485 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2717242 T7576 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@361509a6 name:ZooKeeperConnection Watcher:127.0.0.1:51436/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2717242 T7485 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2717256 T7485 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2718089 T7508 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2718091 T7508 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "base_url":"http://127.0.0.1:51449/uhp/l",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:51449_uhp%2Fl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node3"}
[junit4] 2> 2718108 T7559 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718109 T7527 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718110 T7543 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718110 T7514 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718110 T7576 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718111 T7507 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 2718269 T7485 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51453_uhp%2Fl
[junit4] 2> 2718272 T7485 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51453_uhp%2Fl
[junit4] 2> 2718280 T7576 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2718281 T7559 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2718282 T7514 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 2718282 T7543 oascc.ZkS
[...truncated too long message...]
/overseer_elect/election/90615455191400454-127.0.0.1:51443_uhp%2Fl-n_0000000001 (0)
[junit4] 1> /solr/overseer_elect/election/90615455191400456-127.0.0.1:51446_uhp%2Fl-n_0000000002 (0)
[junit4] 1> /solr/overseer_elect/election/90615455191400460-127.0.0.1:51453_uhp%2Fl-n_0000000004 (0)
[junit4] 1> /solr/overseer_elect/election/90615455191400451-127.0.0.1:51439_uhp%2Fl-n_0000000000 (0)
[junit4] 1> /solr/overseer_elect/election/90615455191400458-127.0.0.1:51449_uhp%2Fl-n_0000000003 (0)
[junit4] 1> /solr/overseer_elect/leader (0)
[junit4] 1> DATA:
[junit4] 1> {"id":"90615455191400451-127.0.0.1:51439_uhp%2Fl-n_0000000000"}
[junit4] 1> /zookeeper (1)
[junit4] 1> DATA:
[junit4] 1>
[junit4] 1>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ShardSplitTest -Dtests.method=testDistribSearch -Dtests.seed=26991E9917156F3 -Dtests.slow=true -Dtests.locale=es_SV -Dtests.timezone=Africa/Tunis -Dtests.file.encoding=UTF-8
[junit4] FAILURE 81.2s | ShardSplitTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Wrong doc count on shard1_0 expected:<130> but was:<82>
[junit4] > at __randomizedtesting.SeedInfo.seed([26991E9917156F3:838F1FF1E62E36CF]:0)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:477)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.splitByUniqueKeyTest(ShardSplitTest.java:249)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:113)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 2775478 T7485 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 81237 T7484 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> 2776659 T7576 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: test params are: codec=Lucene41, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=es_SV, timezone=Africa/Tunis
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.7.0_45 (64-bit)/cpus=2,threads=1,free=315517728,total=434110464
[junit4] 2> NOTE: All tests run in this JVM: [DefaultValueUpdateProcessorTest, TestSuggestSpellingConverter, HighlighterTest, ClusterStateTest, RecoveryZkTest, WordBreakSolrSpellCheckerTest, TestPseudoReturnFields, CurrencyFieldXmlFileTest, AliasIntegrationTest, DateMathParserTest, CircularListTest, TestLMJelinekMercerSimilarityFactory, PreAnalyzedFieldTest, TestHashPartitioner, TestPHPSerializedResponseWriter, UpdateRequestProcessorFactoryTest, NumericFieldsTest, TestFastLRUCache, TestCopyFieldCollectionResource, TestArbitraryIndexDir, TestCoreContainer, DeleteShardTest, TestSearchPerf, BadCopyFieldTest, SpellingQueryConverterTest, TestJoin, TestQueryUtils, TestAnalyzedSuggestions, HdfsSyncSliceTest, QueryElevationComponentTest, ZkNodePropsTest, TestQueryTypes, TestLFUCache, AutoCommitTest, CopyFieldTest, TimeZoneUtilsTest, TestReplicationHandler, TestBadConfig, CoreContainerCoreInitFailuresTest, TestPerFieldSimilarity, TestSolrXml, SolrRequestParserTest, TestDynamicFieldResource, ResponseLogComponentTest, ShardRoutingTest, DirectSolrSpellCheckerTest, BadComponentTest, TestWordDelimiterFilterFactory, ConvertedLegacyTest, SolrInfoMBeanTest, IndexBasedSpellCheckerTest, EchoParamsTest, TestRandomMergePolicy, HighlighterConfigTest, SOLR749Test, NotRequiredUniqueKeyTest, TestDefaultSearchFieldResource, ReturnFieldsTest, TestSurroundQueryParser, MultiTermTest, TestFieldCollectionResource, OpenExchangeRatesOrgProviderTest, TestUtils, SortByFunctionTest, BasicDistributedZk2Test, PathHierarchyTokenizerFactoryTest, TestSweetSpotSimilarityFactory, StatelessScriptUpdateProcessorFactoryTest, TestQuerySenderNoQuery, HdfsLockFactoryTest, TestDistributedSearch, SuggesterFSTTest, BasicZkTest, OutputWriterTest, TestStressVersions, TestSolrQueryParserResource, ResourceLoaderTest, TestQuerySenderListener, TestExtendedDismaxParser, TestSchemaVersionResource, HdfsRecoveryZkTest, TermVectorComponentTest, DocumentBuilderTest, TestSolr4Spatial, TestRTGBase, TestCharFilters, DocValuesMissingTest, MBeansHandlerTest, BJQParserTest, ShardRoutingCustomTest, TestCSVLoader, TestFastWriter, TestDistributedGrouping, HdfsChaosMonkeySafeLeaderTest, TestFieldTypeCollectionResource, IndexSchemaRuntimeFieldTest, TestTrie, RequestHandlersTest, TestFoldingMultitermQuery, CoreAdminHandlerTest, TestShardHandlerFactory, BasicFunctionalityTest, TestCSVResponseWriter, UniqFieldsUpdateProcessorFactoryTest, SliceStateUpdateTest, TestCollationField, DirectUpdateHandlerOptimizeTest, TestManagedSchemaFieldResource, SolrTestCaseJ4Test, TestNRTOpen, TestClassNameShortening, ChaosMonkeySafeLeaderTest, TestSolrQueryParserDefaultOperatorResource, TestBinaryResponseWriter, TestFuzzyAnalyzedSuggestions, SolrIndexSplitterTest, QueryEqualityTest, CollectionsAPIDistributedZkTest, SuggesterTSTTest, LegacyHTMLStripCharFilterTest, TestDocumentBuilder, ExternalFileFieldSortTest, TestBM25SimilarityFactory, AssignTest, TestRecoveryHdfs, TestPhraseSuggestions, TestNoOpRegenerator, HdfsDirectoryTest, PeerSyncTest, TestIndexSearcher, TestSchemaSimilarityResource, TestMultiCoreConfBootstrap, RAMDirectoryFactoryTest, TestNumberUtils, LoggingHandlerTest, TestRemoteStreaming, UnloadDistributedZkTest, LukeRequestHandlerTest, XmlUpdateRequestHandlerTest, TermsComponentTest, TestSolrQueryParser, TestRealTimeGet, PrimUtilsTest, RegexBoostProcessorTest, TestStressRecovery, ZkCLITest, BadIndexSchemaTest, SuggesterWFSTTest, CurrencyFieldOpenExchangeTest, SynonymTokenizerTest, SliceStateTest, TestUpdate, TestDFRSimilarityFactory, TestDynamicFieldCollectionResource, TestSolrXmlPersistor, StatsComponentTest, TestJmxMonitoredMap, TestGroupingSearch, FileBasedSpellCheckerTest, BasicDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, TestFaceting, TestRecovery, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestStressReorder, TestReload, HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery, SimpleFacetsTest, SolrCoreTest, SpellCheckComponentTest, SolrCmdDistributorTest, TestSort, TestFiltering, TestFunctionQuery, TestLazyCores, DirectUpdateHandlerTest, OverseerCollectionProcessorTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, SimplePostToolTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, DocValuesTest, SuggesterTest, TestStressLucene, SpatialFilterTest, PolyFieldTest, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, DocValuesMultiTest, DistanceFunctionTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, DebugComponentTest, CacheHeaderTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, TestWriterPerf, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, TestOmitPositions, TestValueSourceCache, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, SolrPluginUtilsTest, IndexSchemaTest, TestJmxIntegration, JSONWriterTest, QueryParsingTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, PingRequestHandlerTest, CSVRequestHandlerTest, TestComponentsName, SearchHandlerTest, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, SolrIndexConfigTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, SampleTest, TestDocSet, TestBinaryField, MinimalSchemaTest, TestElisionMultitermQuery, TestConfig, TestSolrCoreProperties, TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestLuceneMatchVersion, SpellPossibilityIteratorTest, TestCodecSupport, TestXIncludeConfig, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestDefaultSimilarityFactory, TestFastOutputStream, ScriptEngineTest, URLClassifyProcessorTest, PluginInfoTest, ChaosMonkeyNothingIsSafeTest, TestSolrXMLSerializer, TestSystemIdResolver, DateFieldTest, DOMUtilTest, TestSolrJ, TestLRUCache, SystemInfoHandlerTest, UUIDFieldTest, FileUtilsTest, CustomCollectionTest, ShardSplitTest]
[junit4] Completed in 82.55s, 1 test, 1 failure <<< FAILURES!
[...truncated 114 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:422: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:402: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:483: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1259: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:902: There were test failures: 327 suites, 1451 tests, 1 failure, 40 ignored (5 assumptions)
Total time: 82 minutes 12 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.7.0) - Build # 946 -
Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/946/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
All tests passed
Build Log:
[...truncated 9765 lines...]
[junit4] JVM J0: stdout was not empty, see: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20131025_122106_052.sysout
[junit4] >>> JVM J0: stdout (verbatim) ----
[junit4] #
[junit4] # A fatal error has been detected by the Java Runtime Environment:
[junit4] #
[junit4] # SIGSEGV (0xb) at pc=0x000000010feeba2b, pid=185, tid=134147
[junit4] #
[junit4] # JRE version: Java(TM) SE Runtime Environment (7.0_45-b18) (build 1.7.0_45-b18)
[junit4] # Java VM: Java HotSpot(TM) 64-Bit Server VM (24.45-b08 mixed mode bsd-amd64 )
[junit4] # Problematic frame:
[junit4] # C [libjava.dylib+0x9a2b] JNU_NewStringPlatform+0x1d3
[junit4] #
[junit4] # Failed to write core dump. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
[junit4] #
[junit4] # An error report file with more information is saved as:
[junit4] # /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/hs_err_pid185.log
[junit4] [thread 139779 also had an error]
[junit4] #
[junit4] # If you would like to submit a bug report, please visit:
[junit4] # http://bugreport.sun.com/bugreport/crash.jsp
[junit4] # The crash happened outside the Java Virtual Machine in native code.
[junit4] # See problematic frame for where to report the bug.
[junit4] #
[junit4] <<< JVM J0: EOF ----
[...truncated 1 lines...]
[junit4] ERROR: JVM J0 ended with an exception, command line: /Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/bin/java -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/heapdumps -Dtests.prefix=tests -Dtests.seed=F31293EB573940CD -Xmx512M -Dtests.iters= -Dtests.verbose=false -Dtests.infostream=false -Dtests.codec=random -Dtests.postingsformat=random -Dtests.docvaluesformat=random -Dtests.locale=random -Dtests.timezone=random -Dtests.directory=random -Dtests.linedocsfile=europarl.lines.txt.gz -Dtests.luceneMatchVersion=5.0 -Dtests.cleanthreads=perClass -Djava.util.logging.config.file=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/tools/junit4/logging.properties -Dtests.nightly=false -Dtests.weekly=false -Dtests.slow=true -Dtests.asserts.gracious=false -Dtests.multiplier=1 -DtempDir=. -Djava.io.tmpdir=. -Djunit4.tempDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/temp -Dclover.db.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/clover/db -Djava.security.manager=org.apache.lucene.util.TestSecurityManager -Djava.security.policy=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/tools/junit4/tests.policy -Dlucene.version=5.0-SNAPSHOT -Djetty.testMode=1 -Djetty.insecurerandom=1 -Dsolr.directoryFactory=org.apache.solr.core.MockDirectoryFactory -Djava.awt.headless=true -Dtests.disableHdfs=true -Dfile.encoding=UTF-8 -classpath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/classes/test:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-test-framework/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/test-framework/lib/junit4-ant-2.0.10.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test-files:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/test-framework/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/codecs/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/codecs/lucene-codecs-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/memory/lucene-memory-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/spatial/lucene-spatial-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/queries/lucene-queries-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/join/lucene-join-5.0-SNAPSHOT.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/commons-cli-1.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/commons-codec-1.7.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/commons-configuration-1.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/commons-fileupload-1.2.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/commons-lang-2.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/concurrentlinkedhashmap-lru-1.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/dom4j-1.6.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/guava-14.0.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/hadoop-annotations-2.2.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/hadoop-auth-2.2.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/hadoop-common-2.2.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/hadoop-hdfs-2.2.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/hppc-0.5.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/joda-time-2.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/org.restlet-2.1.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/org.restlet.ext.servlet-2.1.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/protobuf-java-2.5.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/lib/spatial4j-0.3.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/commons-io-2.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/httpclient-4.2.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/httpcore-4.2.5.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/httpmime-4.2.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/jcl-over-slf4j-1.6.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/jul-to-slf4j-1.6.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/log4j-1.2.16.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/noggit-0.5.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/slf4j-api-1.6.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/slf4j-log4j12-1.6.6.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/wstx-asl-3.2.7.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/lib/zookeeper-3.4.5.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-continuation-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-deploy-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-http-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-io-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-jmx-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-security-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-server-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-servlet-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-util-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-webapp-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/jetty-xml-8.1.10.v20130312.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/lib/servlet-api-3.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build/core/classes/java:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/test-framework/lib/junit-4.10.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/test-framework/lib/randomizedtesting-runner-2.0.10.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/cglib-nodep-2.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/commons-collections-3.2.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/dom4j-1.6.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/easymock-3.0.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/hadoop-common-2.2.0-tests.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/hadoop-hdfs-2.2.0-tests.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/hppc-0.5.2.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/javax.servlet-api-3.0.1.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/jersey-core-1.16.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/jetty-6.1.26.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/jetty-util-6.1.26.jar:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/test-lib/objenesis-1.2.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/Users/jenkins/.ant/lib/ivy-2.3.0.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/lib/tools.jar:/Users/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.10.jar -ea:org.apache.lucene... -ea:org.apache.solr... com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -flush -eventsfile /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20131025_122106_052.events @/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/temp/junit4-J0-20131025_122106_052.suites
[junit4] ERROR: JVM J0 ended with an exception: Forked process returned with error code: 134. Very likely a JVM crash. Process output piped in logs above.
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1254)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:67)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:822)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:819)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] at java.lang.Thread.run(Thread.java:744)
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:422: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:402: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:483: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1259: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:902: At least one slave process threw an exception, first: Forked process returned with error code: 134. Very likely a JVM crash. Process output piped in logs above.
Total time: 41 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure