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 2014/07/19 08:06:12 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 1720
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1720/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.HttpPartitionTest.testDistribSearch
Error Message:
No registered leader was found after waiting for 60000ms , collection: c8n_1x3_lf slice: shard1
Stack Trace:
org.apache.solr.common.SolrException: No registered leader was found after waiting for 60000ms , collection: c8n_1x3_lf slice: shard1
at __randomizedtesting.SeedInfo.seed([77390A73E2623706:F6DF846B953D573A]:0)
at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:567)
at org.apache.solr.cloud.HttpPartitionTest.testRf3WithLeaderFailover(HttpPartitionTest.java:371)
at org.apache.solr.cloud.HttpPartitionTest.doTest(HttpPartitionTest.java:150)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:863)
at sun.reflect.GeneratedMethodAccessor38.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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.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:65)
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:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
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:65)
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:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11986 lines...]
[junit4] Suite: org.apache.solr.cloud.HttpPartitionTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/init-core-data-001
[junit4] 2> 4850617 T11812 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /l/bg
[junit4] 2> 4850621 T11812 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 4850622 T11812 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4850623 T11813 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 4850724 T11812 oasc.ZkTestServer.run start zk server on port:52844
[junit4] 2> 4850725 T11812 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4850730 T11819 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6ddf96d2 name:ZooKeeperConnection Watcher:127.0.0.1:52844 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4850730 T11812 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4850730 T11812 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 4850738 T11812 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4850743 T11821 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4ea036e0 name:ZooKeeperConnection Watcher:127.0.0.1:52844/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4850743 T11812 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4850743 T11812 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 4850750 T11812 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 4850756 T11812 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 4850760 T11812 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 4850766 T11812 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 4850767 T11812 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 4850777 T11812 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 4850777 T11812 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 4850785 T11812 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4850786 T11812 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4850793 T11812 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 4850794 T11812 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 4850800 T11812 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 4850801 T11812 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 4850808 T11812 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 4850808 T11812 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 4850814 T11812 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 4850815 T11812 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 4850821 T11812 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4850822 T11812 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 4850829 T11812 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4850830 T11812 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4850834 T11812 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 4850835 T11812 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 4850842 T11812 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 4850843 T11812 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 4852392 T11812 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4852400 T11812 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52848
[junit4] 2> 4852401 T11812 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 4852402 T11812 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4852402 T11812 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-002
[junit4] 2> 4852403 T11812 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-002/'
[junit4] 2> 4852436 T11812 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-002/solr.xml
[junit4] 2> 4852450 T11812 oasc.CoreContainer.<init> New CoreContainer 1347437366
[junit4] 2> 4852451 T11812 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-002/]
[junit4] 2> 4852451 T11812 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 4852452 T11812 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 4852453 T11812 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 4852453 T11812 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 4852453 T11812 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 4852453 T11812 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 4852454 T11812 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 4852454 T11812 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 4852454 T11812 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 4852454 T11812 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4852456 T11812 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4852456 T11812 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4852456 T11812 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52844/solr
[junit4] 2> 4852457 T11812 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 4852458 T11812 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4852461 T11832 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3c4a8fad name:ZooKeeperConnection Watcher:127.0.0.1:52844 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4852462 T11812 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4852466 T11812 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4852468 T11834 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b94f620 name:ZooKeeperConnection Watcher:127.0.0.1:52844/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4852468 T11812 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4852471 T11812 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 4852478 T11812 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 4852487 T11812 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 4852495 T11812 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 4852502 T11812 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 4852509 T11812 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 4852514 T11812 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52847_l%2Fbg
[junit4] 2> 4852515 T11812 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52847_l%2Fbg
[junit4] 2> 4852523 T11812 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 4852527 T11812 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 4852535 T11812 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:52847_l%2Fbg
[junit4] 2> 4852536 T11812 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 4852541 T11812 oasc.Overseer.start Overseer (id=92127091068436483-127.0.0.1:52847_l%2Fbg-n_0000000000) starting
[junit4] 2> 4852547 T11812 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 4852571 T11836 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 4852572 T11812 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 4852577 T11812 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 4852582 T11812 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 4852591 T11835 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 4852596 T11837 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 4852596 T11837 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 4852597 T11837 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 4852600 T11835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4852602 T11835 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:52847/l/bg",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:52847_l%2Fbg",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 4852602 T11835 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 4852603 T11835 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 4852607 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4852609 T11834 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> 4853599 T11837 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 4853599 T11837 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 4853601 T11837 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 4853601 T11837 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 4853604 T11837 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 4853605 T11837 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-002/collection1/'
[junit4] 2> 4853606 T11837 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 4853606 T11837 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 4853606 T11837 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 4853644 T11837 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 4853649 T11837 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 4853655 T11837 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 4853660 T11837 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 4853777 T11837 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4853784 T11837 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 4853785 T11837 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4853813 T11837 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4853818 T11837 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4853821 T11837 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4853824 T11837 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4853824 T11837 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4853824 T11837 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4853826 T11837 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4853827 T11837 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4853827 T11837 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 4853827 T11837 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4853827 T11837 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/control/data/
[junit4] 2> 4853828 T11837 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@20ed3aa7
[junit4] 2> 4853828 T11837 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/control/data
[junit4] 2> 4853829 T11837 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/control/data/index/
[junit4] 2> 4853829 T11837 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 4853829 T11837 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/control/data/index
[junit4] 2> 4853829 T11837 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@57f519d4
[junit4] 2> 4853830 T11837 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@59ebae95 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@536e9ab9),segFN=segments_1,generation=1}
[junit4] 2> 4853830 T11837 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 4853833 T11837 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 4853834 T11837 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 4853834 T11837 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 4853835 T11837 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 4853836 T11837 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 4853836 T11837 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 4853837 T11837 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4853837 T11837 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4853837 T11837 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4853838 T11837 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 4853838 T11837 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 4853838 T11837 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 4853839 T11837 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 4853839 T11837 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 4853839 T11837 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 4853840 T11837 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 4853840 T11837 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 4853847 T11837 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4853850 T11837 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 4853850 T11837 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 4853850 T11837 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=44, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.30333173225505516]
[junit4] 2> 4853851 T11837 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@59ebae95 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@536e9ab9),segFN=segments_1,generation=1}
[junit4] 2> 4853852 T11837 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 4853852 T11837 oass.SolrIndexSearcher.<init> Opening Searcher@1186a8bc[collection1] main
[junit4] 2> 4853852 T11837 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 4853855 T11837 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 4853855 T11837 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 4853856 T11837 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4853857 T11837 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 4853857 T11837 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4853859 T11837 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 4853860 T11837 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4853861 T11837 oascc.SolrZkClient.makePath makePath: /configs/conf1/_rest_managed.json
[junit4] 2> 4853869 T11837 oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new znode /configs/conf1/_rest_managed.json
[junit4] 2> 4853869 T11837 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4853869 T11837 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 4853873 T11838 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1186a8bc[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 4853874 T11837 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 4853876 T11841 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:52847/l/bg collection:control_collection shard:shard1
[junit4] 2> 4853877 T11812 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 4853877 T11812 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 4853878 T11841 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 4853884 T11812 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4853887 T11844 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3d76fcd2 name:ZooKeeperConnection Watcher:127.0.0.1:52844/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4853887 T11812 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4853892 T11812 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 4853895 T11841 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 4853898 T11812 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 4853899 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4853901 T11841 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 4853901 T11841 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4267 name=collection1 org.apache.solr.core.SolrCore@5a1bbf45 url=http://127.0.0.1:52847/l/bg/collection1 node=127.0.0.1:52847_l%2Fbg C4267_STATE=coll:control_collection core:collection1 props:{state=down, base_url=http://127.0.0.1:52847/l/bg, core=collection1, node_name=127.0.0.1:52847_l%2Fbg}
[junit4] 2> 4853902 T11841 C4267 P52847 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:52847/l/bg/collection1/
[junit4] 2> 4853902 T11841 C4267 P52847 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 4853902 T11841 C4267 P52847 oasc.SyncStrategy.syncToMe http://127.0.0.1:52847/l/bg/collection1/ has no replicas
[junit4] 2> 4853903 T11841 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:52847/l/bg/collection1/ shard1
[junit4] 2> 4853903 T11841 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 4853914 T11835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4853922 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4853932 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4853943 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4854048 T11834 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> 4854048 T11844 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> 4854091 T11841 oasc.ZkController.register We are http://127.0.0.1:52847/l/bg/collection1/ and leader is http://127.0.0.1:52847/l/bg/collection1/
[junit4] 2> 4854091 T11841 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52847/l/bg
[junit4] 2> 4854091 T11841 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 4854092 T11841 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 4854092 T11841 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 4854105 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4854105 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4854106 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4854108 T11841 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4854110 T11835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4854112 T11835 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:52847/l/bg",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:52847_l%2Fbg",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 4854115 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4854224 T11844 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> 4854224 T11834 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> 4855458 T11812 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 4855459 T11812 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4855464 T11812 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52853
[junit4] 2> 4855465 T11812 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 4855466 T11812 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4855466 T11812 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-003
[junit4] 2> 4855466 T11812 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-003/'
[junit4] 2> 4855505 T11812 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-003/solr.xml
[junit4] 2> 4855520 T11812 oasc.CoreContainer.<init> New CoreContainer 101975148
[junit4] 2> 4855521 T11812 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-003/]
[junit4] 2> 4855521 T11812 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 4855521 T11812 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 4855522 T11812 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 4855523 T11812 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 4855523 T11812 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 4855523 T11812 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 4855524 T11812 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 4855524 T11812 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 4855525 T11812 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 4855526 T11812 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4855526 T11812 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4855526 T11812 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4855526 T11812 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52844/solr
[junit4] 2> 4855527 T11812 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 4855528 T11812 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4855530 T11855 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@115fa66d name:ZooKeeperConnection Watcher:127.0.0.1:52844 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4855531 T11812 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4855536 T11812 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4855538 T11857 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5551057f name:ZooKeeperConnection Watcher:127.0.0.1:52844/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4855539 T11812 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4855555 T11812 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 4856564 T11812 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52852_l%2Fbg
[junit4] 2> 4856566 T11812 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52852_l%2Fbg
[junit4] 2> 4856582 T11858 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 4856583 T11858 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 4856585 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4856585 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4856585 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4856585 T11858 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 4856589 T11835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4856590 T11835 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:52852/l/bg",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:52852_l%2Fbg",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 4856591 T11835 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 4856591 T11835 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 4856596 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4856597 T11834 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> 4856598 T11844 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> 4856598 T11857 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> 4857588 T11858 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 4857588 T11858 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 4857590 T11858 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 4857590 T11858 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 4857592 T11858 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 4857592 T11858 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-003/collection1/'
[junit4] 2> 4857594 T11858 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-003/collection1/lib/.svn/' to classloader
[junit4] 2> 4857594 T11858 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-003/collection1/lib/classes/' to classloader
[junit4] 2> 4857595 T11858 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-003/collection1/lib/README' to classloader
[junit4] 2> 4857635 T11858 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 4857645 T11858 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 4857648 T11858 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 4857651 T11858 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 4857759 T11858 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4857767 T11858 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 4857767 T11858 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4857787 T11858 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4857791 T11858 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4857795 T11858 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4857798 T11858 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4857798 T11858 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4857799 T11858 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4857801 T11858 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4857802 T11858 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4857802 T11858 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 4857802 T11858 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4857803 T11858 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/jetty1/
[junit4] 2> 4857803 T11858 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@20ed3aa7
[junit4] 2> 4857803 T11858 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/jetty1
[junit4] 2> 4857804 T11858 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/jetty1/index/
[junit4] 2> 4857804 T11858 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 4857804 T11858 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/jetty1/index
[junit4] 2> 4857804 T11858 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@63a2e7fa
[junit4] 2> 4857805 T11858 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@673f0ed0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7cf42cd0),segFN=segments_1,generation=1}
[junit4] 2> 4857805 T11858 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 4857809 T11858 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 4857810 T11858 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 4857810 T11858 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 4857810 T11858 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 4857811 T11858 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 4857811 T11858 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 4857812 T11858 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4857812 T11858 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4857812 T11858 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4857814 T11858 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 4857814 T11858 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 4857815 T11858 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 4857815 T11858 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 4857815 T11858 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 4857816 T11858 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 4857816 T11858 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 4857817 T11858 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 4857826 T11858 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4857831 T11858 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 4857831 T11858 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 4857832 T11858 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=44, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.30333173225505516]
[junit4] 2> 4857832 T11858 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@673f0ed0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7cf42cd0),segFN=segments_1,generation=1}
[junit4] 2> 4857832 T11858 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 4857833 T11858 oass.SolrIndexSearcher.<init> Opening Searcher@373cc7b0[collection1] main
[junit4] 2> 4857833 T11858 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 4857835 T11858 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 4857836 T11858 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 4857837 T11858 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4857837 T11858 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 4857837 T11858 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4857841 T11858 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json
[junit4] 2> 4857841 T11858 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4857841 T11858 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed
[junit4] 2> 4857842 T11858 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 4857861 T11859 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@373cc7b0[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 4857864 T11858 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 4857866 T11862 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:52852/l/bg collection:collection1 shard:shard2
[junit4] 2> 4857867 T11812 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 4857867 T11812 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 4857869 T11862 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 4857883 T11862 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 4857886 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4857889 T11835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4857891 T11862 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 4857891 T11862 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4268 name=collection1 org.apache.solr.core.SolrCore@398ee6c4 url=http://127.0.0.1:52852/l/bg/collection1 node=127.0.0.1:52852_l%2Fbg C4268_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:52852/l/bg, core=collection1, node_name=127.0.0.1:52852_l%2Fbg}
[junit4] 2> 4857892 T11862 C4268 P52852 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:52852/l/bg/collection1/
[junit4] 2> 4857892 T11862 C4268 P52852 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 4857892 T11862 C4268 P52852 oasc.SyncStrategy.syncToMe http://127.0.0.1:52852/l/bg/collection1/ has no replicas
[junit4] 2> 4857893 T11862 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:52852/l/bg/collection1/ shard2
[junit4] 2> 4857893 T11862 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 4857896 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4857910 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4857918 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4858023 T11857 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> 4858023 T11844 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> 4858023 T11834 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> 4858068 T11862 oasc.ZkController.register We are http://127.0.0.1:52852/l/bg/collection1/ and leader is http://127.0.0.1:52852/l/bg/collection1/
[junit4] 2> 4858068 T11862 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52852/l/bg
[junit4] 2> 4858068 T11862 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 4858068 T11862 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 4858069 T11862 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 4858072 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4858073 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4858073 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4858075 T11862 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4858077 T11835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4858079 T11835 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:52852/l/bg",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:52852_l%2Fbg",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 4858085 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4858189 T11834 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> 4858189 T11844 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> 4858189 T11857 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> 4859494 T11812 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 4859494 T11812 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 4859500 T11812 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52857
[junit4] 2> 4859501 T11812 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 4859502 T11812 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 4859503 T11812 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-004
[junit4] 2> 4859503 T11812 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-004/'
[junit4] 2> 4859543 T11812 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-004/solr.xml
[junit4] 2> 4859555 T11812 oasc.CoreContainer.<init> New CoreContainer 402389267
[junit4] 2> 4859556 T11812 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-004/]
[junit4] 2> 4859556 T11812 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 4859557 T11812 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 4859557 T11812 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 4859557 T11812 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 4859557 T11812 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 4859558 T11812 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 4859558 T11812 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 4859558 T11812 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 4859559 T11812 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 4859559 T11812 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4859560 T11812 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4859560 T11812 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 4859561 T11812 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:52844/solr
[junit4] 2> 4859561 T11812 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 4859563 T11812 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4859566 T11874 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@94b7373 name:ZooKeeperConnection Watcher:127.0.0.1:52844 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4859566 T11812 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4859570 T11812 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4859573 T11876 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3fd04503 name:ZooKeeperConnection Watcher:127.0.0.1:52844/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4859573 T11812 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4859592 T11812 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 4860599 T11812 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52856_l%2Fbg
[junit4] 2> 4860601 T11812 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52856_l%2Fbg
[junit4] 2> 4860618 T11877 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 4860618 T11877 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 4860620 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4860620 T11877 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 4860620 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4860622 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4860623 T11835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4860624 T11835 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:52856/l/bg",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:52856_l%2Fbg",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 4860625 T11835 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 4860625 T11835 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 4860631 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4860633 T11857 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> 4860633 T11876 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> 4860633 T11844 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> 4860633 T11834 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> 4861622 T11877 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 4861622 T11877 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 4861624 T11877 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 4861624 T11877 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 4861627 T11877 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 4861627 T11877 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-004/collection1/'
[junit4] 2> 4861628 T11877 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-004/collection1/lib/.svn/' to classloader
[junit4] 2> 4861628 T11877 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 4861629 T11877 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 4861664 T11877 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 4861675 T11877 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 4861678 T11877 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 4861683 T11877 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 4861801 T11877 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 4861811 T11877 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 4861811 T11877 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 4861838 T11877 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4861842 T11877 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 4861846 T11877 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4861849 T11877 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4861849 T11877 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4861849 T11877 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 4861852 T11877 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 4861852 T11877 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 4861852 T11877 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 4861853 T11877 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 4861853 T11877 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/jetty2/
[junit4] 2> 4861853 T11877 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@20ed3aa7
[junit4] 2> 4861854 T11877 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/jetty2
[junit4] 2> 4861854 T11877 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/jetty2/index/
[junit4] 2> 4861854 T11877 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 4861855 T11877 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001/tempDir-001/jetty2/index
[junit4] 2> 4861855 T11877 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@388b2353
[junit4] 2> 4861856 T11877 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@b531d8f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ba7fea8),segFN=segments_1,generation=1}
[junit4] 2> 4861856 T11877 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 4861859 T11877 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 4861860 T11877 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 4861860 T11877 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 4861860 T11877 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 4861860 T11877 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 4861861 T11877 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 4861862 T11877 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4861862 T11877 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 4861863 T11877 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 4861863 T11877 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 4861864 T11877 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 4861864 T11877 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 4861864 T11877 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 4861865 T11877 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 4861865 T11877 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 4861866 T11877 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 4861866 T11877 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 4861874 T11877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 4861877 T11877 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 4861877 T11877 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 4861878 T11877 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=44, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.30333173225505516]
[junit4] 2> 4861879 T11877 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@b531d8f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2ba7fea8),segFN=segments_1,generation=1}
[junit4] 2> 4861880 T11877 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 4861880 T11877 oass.SolrIndexSearcher.<init> Opening Searcher@5735e480[collection1] main
[junit4] 2> 4861880 T11877 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 4861882 T11877 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 4861883 T11877 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 4861884 T11877 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4861884 T11877 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 4861884 T11877 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4861887 T11877 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream Read 38 bytes from znode /configs/conf1/_rest_managed.json
[junit4] 2> 4861887 T11877 oasr.ManagedResourceStorage.load Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4861887 T11877 oasr.ManagedResource.reloadFromStorage Loaded initArgs {} for /rest/managed
[junit4] 2> 4861887 T11877 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 4861892 T11878 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5735e480[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 4861894 T11877 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 4861896 T11881 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:52856/l/bg collection:collection1 shard:shard1
[junit4] 2> 4861897 T11812 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 4861898 T11812 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 4861898 T11881 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 4861900 T11812 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4861901 T11812 oasc.AbstractFullDistribZkTestBase.waitForThingsToLevelOut Wait for recoveries to finish - wait 30000 for each attempt
[junit4] 2> 4861901 T11812 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):30000
[junit4] 2> 4861903 T11812 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4861913 T11881 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 4861917 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4861918 T11881 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 4861918 T11881 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4269 name=collection1 org.apache.solr.core.SolrCore@75eb4cd3 url=http://127.0.0.1:52856/l/bg/collection1 node=127.0.0.1:52856_l%2Fbg C4269_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:52856/l/bg, core=collection1, node_name=127.0.0.1:52856_l%2Fbg}
[junit4] 2> 4861919 T11881 C4269 P52856 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:52856/l/bg/collection1/
[junit4] 2> 4861919 T11881 C4269 P52856 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 4861919 T11881 C4269 P52856 oasc.SyncStrategy.syncToMe http://127.0.0.1:52856/l/bg/collection1/ has no replicas
[junit4] 2> 4861919 T11881 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:52856/l/bg/collection1/ shard1
[junit4] 2> 4861920 T11881 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 4861920 T11835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4861927 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4861932 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4861937 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4862043 T11857 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> 4862043 T11834 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> 4862043 T11844 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> 4862043 T11876 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> 4862094 T11881 oasc.ZkController.register We are http://127.0.0.1:52856/l/bg/collection1/ and leader is http://127.0.0.1:52856/l/bg/collection1/
[junit4] 2> 4862094 T11881 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52856/l/bg
[junit4] 2> 4862095 T11881 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 4862095 T11881 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 4862095 T11881 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 4862098 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4862098 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4862099 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4862099 T11881 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4862102 T11835 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4862103 T11835 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:52856/l/bg",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:52856_l%2Fbg",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 4862109 T11834 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 4862214 T11876 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> 4862214 T11834 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> 4862214 T11857 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> 4862214 T11844 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> 4862906 T11812 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 4862908 T11812 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Recoveries finished - collection: collection1
[junit4] 2> 4862910 T11842 oasc.SocketProxy$Acceptor.run accepted Socket[addr=/127.0.0.1,port=52860,localport=52847], receiveBufferSize:146988
[junit4] 2> 4862920 T11842 oasc.SocketProxy$Bridge.<init> proxy connection Socket[addr=/127.0.0.1,port=52848,localport=52861], receiveBufferSize=146988
[junit4] 2> ASYNC NEW_CORE C4270 name=collection1 org.apache.solr.core.SolrCore@5a1bbf45 url=http://127.0.0.1:52847/l/bg/collection1 node=127.0.0.1:52847_l%2Fbg C4270_STATE=coll:control_collection core:collection1 props:{state=active, base_url=http://127.0.0.1:52847/l/bg, core=collection1, node_name=127.0.0.1:52847_l%2Fbg, leader=true}
[junit4] 2> 4862920 T11826 C4270 P52847 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4862921 T11826 C4270 P52847 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 4862922 T11826 C4270 P52847 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 4862922 T11826 C4270 P52847 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 4862923 T11826 C4270 P52847 oasup.LogUpdateProcessor.finish [collection1] webapp=/l/bg path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 3
[junit4] 2> 4862925 T11863 oasc.SocketProxy$Acceptor.run accepted Socket[addr=/127.0.0.1,port=52862,localport=52852], receiveBufferSize:146988
[junit4] 2> 4862928 T11863 oasc.SocketProxy$Bridge.<init> proxy connection Socket[addr=/127.0.0.1,port=52853,localport=52863], receiveBufferSize=146988
[junit4] 2> 4862932 T11882 oasc.SocketProxy$Acceptor.run accepted Socket[addr=/127.0.0.1,port=52864,localport=52856], receiveBufferSize:146988
[junit4] 2> 4862935 T11882 oasc.SocketProxy$Bridge.<init> proxy connection Socket[addr=/127.0.0.1,port=52857,localport=52865], receiveBufferSize=146988
[junit4] 2> C4269_STATE=coll:collection1 core:collection1 props:{state=active, base_url=http://127.0.0.1:52856/l/bg, core=collection1, node_name=127.0.0.1:52856_l%2Fbg, leader=true}
[junit4] 2> 4862935 T11870 C4269 P52856 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4862936 T11870 C4269 P52856 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 4862937 T11870 C4269 P52856 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 4862937 T11870 C4269 P52856 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 4862938 T11870 C4269 P52856 oasup.LogUpdateProcessor.finish [collection1] webapp=/l/bg path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:52852/l/bg/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 3
[junit4] 2> 4862941 T11863 oasc.SocketProxy$Acceptor.run accepted Socket[addr=/127.0.0.1,port=52866,localport=52852], receiveBufferSize:146988
[junit4] 2> 4862943 T11863 oasc.SocketProxy$Bridge.<init> proxy connection Socket[addr=/127.0.0.1,port=52853,localport=52867], receiveBufferSize=146988
[junit4] 2> ASYNC NEW_CORE C4271 name=collection1 org.apache.solr.core.SolrCore@398ee6c4 url=http://127.0.0.1:52852/l/bg/collection1 node=127.0.0.1:52852_l%2Fbg C4271_STATE=coll:collection1 core:collection1 props:{state=active, base_url=http://127.0.0.1:52852/l/bg, core=collection1, node_name=127.0.0.1:52852_l%2Fbg, leader=true}
[junit4] 2> 4862944 T11849 C4271 P52852 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4862945 T11849 C4271 P52852 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 4862945 T11849 C4271 P52852 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 4862947 T11849 C4271 P52852 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 4862947 T11849 C4271 P52852 oasup.LogUpdateProcessor.finish [collection1] webapp=/l/bg path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:52852/l/bg/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 3
[junit4] 2> 4862948 T11848 C4271 P52852 oasup.LogUpdateProcessor.fini
[...truncated too long message...]
re
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-77390A73E2623706-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene49), sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=en_IE, timezone=Asia/Pontianak
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_11 (64-bit)/cpus=2,threads=1,free=172751848,total=391946240
[junit4] 2> NOTE: All tests run in this JVM: [TestCollectionAPI, TestExceedMaxTermLength, TestFoldingMultitermQuery, StatelessScriptUpdateProcessorFactoryTest, SuggesterWFSTTest, ReplicationFactorTest, TestDocumentBuilder, TestTrie, TestCoreDiscovery, TestXIncludeConfig, TestUpdate, AnalysisErrorHandlingTest, TestMaxScoreQueryParser, RequiredFieldsTest, TestBinaryResponseWriter, TestLRUCache, AddSchemaFieldsUpdateProcessorFactoryTest, NoFacetTest, SearchHandlerTest, OverseerStatusTest, DefaultValueUpdateProcessorTest, TestRealTimeGet, CustomCollectionTest, PluginInfoTest, SSLMigrationTest, SoftAutoCommitTest, TestNonNRTOpen, TestFieldSortValues, TestFastLRUCache, TestNRTOpen, TestMissingGroups, XmlUpdateRequestHandlerTest, DistribDocExpirationUpdateProcessorTest, CacheHeaderTest, TestElisionMultitermQuery, TestSchemaVersionResource, NumericFieldsTest, ZkSolrClientTest, TestConfig, PrimitiveFieldTypeTest, StatsComponentTest, DeleteShardTest, SuggestComponentTest, TestIndexingPerformance, DocExpirationUpdateProcessorFactoryTest, CopyFieldTest, TestCloudManagedSchemaConcurrent, SolrIndexSplitterTest, DirectSolrConnectionTest, TestFuzzyAnalyzedSuggestions, TestJoin, BinaryUpdateRequestHandlerTest, TestClassNameShortening, TestGroupingSearch, HdfsRecoveryZkTest, SpellingQueryConverterTest, DeleteReplicaTest, TestPartialUpdateDeduplication, DateFieldTest, SolrPluginUtilsTest, DirectUpdateHandlerOptimizeTest, TestValueSourceCache, TestLMJelinekMercerSimilarityFactory, TestSchemaResource, DistributedExpandComponentTest, TestSolr4Spatial, SpellCheckComponentTest, TestBinaryField, TestSolrXmlPersistence, AtomicUpdatesTest, TestFunctionQuery, AddBlockUpdateTest, MultiTermTest, TestCloudManagedSchema, MergeStrategyTest, PolyFieldTest, TestDocBasedVersionConstraints, ConnectionManagerTest, TestDistributedMissingSort, CursorMarkTest, TestInfoStreamLogging, TestReRankQParserPlugin, TestSolrDeletionPolicy2, FileUtilsTest, TestManagedSchemaFieldResource, TestNonDefinedSimilarityFactory, TestUniqueKeyFieldResource, TestSearcherReuse, TestAddFieldRealTimeGet, TestWriterPerf, TestDefaultSimilarityFactory, SuggesterFSTTest, MultiThreadedOCPTest, SystemInfoHandlerTest, TestDocSet, CursorPagingTest, ZkCLITest, TestManagedStopFilterFactory, TestSimpleQParserPlugin, TestConfigSets, DistributedQueryComponentOptimizationTest, BlockDirectoryTest, HdfsWriteToMultipleCollectionsTest, LeaderElectionIntegrationTest, StandardRequestHandlerTest, TestDynamicFieldResource, CoreMergeIndexesAdminHandlerTest, TestArbitraryIndexDir, IndexBasedSpellCheckerTest, AsyncMigrateRouteKeyTest, TestMergePolicyConfig, TestStressUserVersions, QueryElevationComponentTest, AnalysisAfterCoreReloadTest, StressHdfsTest, PreAnalyzedFieldTest, DistributedSuggestComponentTest, AutoCommitTest, TestFieldResource, DocValuesMultiTest, TestQuerySenderListener, TestCopyFieldCollectionResource, TestBM25SimilarityFactory, TestCSVResponseWriter, ClusterStateUpdateTest, AnalyticsMergeStrategyTest, TestLazyCores, IndexSchemaRuntimeFieldTest, FastVectorHighlighterTest, TestReplicationHandler, TestDistributedGrouping, JSONWriterTest, UUIDFieldTest, TestCodecSupport, DistanceFunctionTest, TestCollationField, CollectionsAPIDistributedZkTest, LukeRequestHandlerTest, DocumentBuilderTest, TestRandomFaceting, ParsingFieldUpdateProcessorsTest, FullSolrCloudDistribCmdsTest, QueryResultKeyTest, TestLeaderElectionZkExpiry, TestSolrQueryParserResource, MBeansHandlerTest, TestComponentsName, SimpleFacetsTest, TermVectorComponentDistributedTest, CoreContainerCoreInitFailuresTest, TestSystemIdResolver, TestBadConfig, SolrIndexConfigTest, TestIndexSearcher, TestSolrQueryParserDefaultOperatorResource, RecoveryZkTest, TestFieldTypeCollectionResource, ExpressionTest, RankQueryTest, TermsComponentTest, TestExpandComponent, TestShardHandlerFactory, TestStressReorder, TestSerializedLuceneMatchVersion, OverseerTest, CoreAdminRequestStatusTest, BasicDistributedZkTest, TestPHPSerializedResponseWriter, SolrCoreTest, NoCacheHeaderTest, TestSolrIndexConfig, ShardSplitTest, HighlighterMaxOffsetTest, DistributedDebugComponentTest, TestRandomDVFaceting, TestComplexPhraseQParserPlugin, SolrCoreCheckLockOnStartupTest, TestHashPartitioner, DistributedQueryComponentCustomSortTest, TestJmxMonitoredMap, ScriptEngineTest, DocValuesMissingTest, RAMDirectoryFactoryTest, TestStressRecovery, ChaosMonkeyNothingIsSafeTest, TestSuggestSpellingConverter, TestShortCircuitedRequests, FunctionTest, ReturnFieldsTest, TestStandardQParsers, SortByFunctionTest, TestRequestStatusCollectionAPI, HdfsBasicDistributedZkTest, BadCopyFieldTest, TestSolrJ, LoggingHandlerTest, TestMultiCoreConfBootstrap, TestReload, CSVRequestHandlerTest, TestSolrXmlPersistor, HdfsBasicDistributedZk2Test, TestImplicitCoreProperties, RangeFacetTest, TestDynamicFieldCollectionResource, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, ShardRoutingTest, BasicZkTest, LeaderElectionTest, TestZkChroot, ShardRoutingCustomTest, TestDistributedSearch, TestFaceting, TestRecovery, DistributedSpellCheckComponentTest, ZkControllerTest, TestStressVersions, HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery, TestCoreContainer, SolrCmdDistributorTest, PeerSyncTest, BadIndexSchemaTest, ConvertedLegacyTest, TestSort, TestFiltering, BasicFunctionalityTest, DirectUpdateHandlerTest, OverseerCollectionProcessorTest, HighlighterTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, SimplePostToolTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SpellCheckCollatorTest, CoreAdminHandlerTest, SolrRequestParserTest, DocValuesTest, SuggesterTSTTest, SuggesterTest, TestStressLucene, SpatialFilterTest, TestCSVLoader, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, QueryEqualityTest, TestRemoteStreaming, TestSolrDeletionPolicy1, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, DebugComponentTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, TestQueryUtils, DirectSolrSpellCheckerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, TestQueryTypes, TestOmitPositions, FileBasedSpellCheckerTest, RequestHandlersTest, PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, RegexBoostProcessorTest, IndexSchemaTest, TestJmxIntegration, UpdateRequestProcessorFactoryTest, QueryParsingTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, PingRequestHandlerTest, TestLFUCache, HighlighterConfigTest, SOLR749Test, UpdateParamsTest, AlternateDirectoryTest, TestQuerySenderNoQuery, ResponseLogComponentTest, BadComponentTest, SampleTest, TestSearchPerf, MinimalSchemaTest, OutputWriterTest, ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestCharFilters, SynonymTokenizerTest, EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestFastWriter, LegacyHTMLStripCharFilterTest, TimeZoneUtilsTest, ResourceLoaderTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, DateMathParserTest, TestSolrXMLSerializer, PrimUtilsTest, DOMUtilTest, ClusterStateTest, TestUtils, ZkNodePropsTest, SliceStateTest, CircularListTest, TestRTGBase, SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, TestTolerantSearch, AbstractAnalyticsStatsTest, AbstractAnalyticsFacetTest, FieldFacetExtrasTest, FieldFacetTest, QueryFacetTest, AliasIntegrationTest, AssignTest, CollectionsAPIAsyncDistributedZkTest, DeleteInactiveReplicaTest, DistribCursorPagingTest, HttpPartitionTest]
[junit4] Completed in 447.63s, 1 test, 1 error <<< FAILURES!
[...truncated 207 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: 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:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1298: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:922: There were test failures: 419 suites, 1712 tests, 1 error, 43 ignored (16 assumptions)
Total time: 153 minutes 33 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 1721
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1721/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
3 tests failed.
REGRESSION: org.apache.solr.MinimalSchemaTest.testAllConfiguredHandlers
Error Message:
Could not initialize class java.lang.UNIXProcess
Stack Trace:
java.lang.NoClassDefFoundError: Could not initialize class java.lang.UNIXProcess
at __randomizedtesting.SeedInfo.seed([D2E7427E993CE1FE:3FF9F9441231E07A]:0)
at java.lang.ProcessImpl.start(ProcessImpl.java:130)
at java.lang.ProcessBuilder.start(ProcessBuilder.java:1029)
at java.lang.Runtime.exec(Runtime.java:620)
at java.lang.Runtime.exec(Runtime.java:450)
at java.lang.Runtime.exec(Runtime.java:347)
at org.apache.solr.handler.admin.SystemInfoHandler.execute(SystemInfoHandler.java:220)
at org.apache.solr.handler.admin.SystemInfoHandler.getSystemInfo(SystemInfoHandler.java:176)
at org.apache.solr.handler.admin.SystemInfoHandler.handleRequestBody(SystemInfoHandler.java:97)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:1981)
at org.apache.solr.util.TestHarness.query(TestHarness.java:295)
at org.apache.solr.util.TestHarness.query(TestHarness.java:278)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:693)
at org.apache.solr.MinimalSchemaTest.testAllConfiguredHandlers(MinimalSchemaTest.java:115)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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.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:65)
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:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
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:65)
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:365)
at java.lang.Thread.run(Thread.java:745)
REGRESSION: org.apache.solr.handler.admin.InfoHandlerTest.testOverriddenHandlers
Error Message:
posix_spawn is not a supported process launch mechanism on this platform.
Stack Trace:
java.lang.Error: posix_spawn is not a supported process launch mechanism on this platform.
at __randomizedtesting.SeedInfo.seed([D2E7427E993CE1FE:34BC4E5960752E4A]:0)
at java.lang.UNIXProcess$1.run(UNIXProcess.java:105)
at java.lang.UNIXProcess$1.run(UNIXProcess.java:94)
at java.security.AccessController.doPrivileged(Native Method)
at java.lang.UNIXProcess.<clinit>(UNIXProcess.java:92)
at java.lang.ProcessImpl.start(ProcessImpl.java:130)
at java.lang.ProcessBuilder.start(ProcessBuilder.java:1029)
at java.lang.Runtime.exec(Runtime.java:620)
at java.lang.Runtime.exec(Runtime.java:450)
at java.lang.Runtime.exec(Runtime.java:347)
at org.apache.solr.handler.admin.SystemInfoHandler.execute(SystemInfoHandler.java:220)
at org.apache.solr.handler.admin.SystemInfoHandler.getSystemInfo(SystemInfoHandler.java:176)
at org.apache.solr.handler.admin.SystemInfoHandler.handleRequestBody(SystemInfoHandler.java:97)
at org.apache.solr.handler.admin.InfoHandlerTest$CountSystemInfoHandler.handleRequestBody(InfoHandlerTest.java:157)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
at org.apache.solr.handler.admin.InfoHandler.handleRequestBody(InfoHandler.java:88)
at org.apache.solr.handler.admin.InfoHandlerTest.handleRequest(InfoHandlerTest.java:168)
at org.apache.solr.handler.admin.InfoHandlerTest.testOverriddenHandlers(InfoHandlerTest.java:98)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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.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:65)
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:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
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:65)
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:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.HttpPartitionTest.testDistribSearch
Error Message:
No registered leader was found after waiting for 60000ms , collection: c8n_1x3_lf slice: shard1
Stack Trace:
org.apache.solr.common.SolrException: No registered leader was found after waiting for 60000ms , collection: c8n_1x3_lf slice: shard1
at __randomizedtesting.SeedInfo.seed([D2E7427E993CE1FE:5301CC66EE6381C2]:0)
at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:567)
at org.apache.solr.cloud.HttpPartitionTest.testRf3WithLeaderFailover(HttpPartitionTest.java:371)
at org.apache.solr.cloud.HttpPartitionTest.doTest(HttpPartitionTest.java:150)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:863)
at sun.reflect.GeneratedMethodAccessor37.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
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.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:65)
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:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
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:65)
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:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10958 lines...]
[junit4] Suite: org.apache.solr.handler.admin.InfoHandlerTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001
[junit4] 2> 1230692 T3015 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1230694 T3015 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 1230694 T3015 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1230695 T3015 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1230696 T3015 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1230696 T3015 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1230776 T3015 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 1230806 T3015 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1230807 T3015 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1230814 T3015 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 1231036 T3015 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1231042 T3015 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1231045 T3015 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1231056 T3015 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1231061 T3015 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1231067 T3015 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1231069 T3015 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1231072 T3015 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1231073 T3015 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1231074 T3015 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1231074 T3015 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1231074 T3015 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1231074 T3015 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr
[junit4] 2> 1231074 T3015 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/'
[junit4] 2> 1231113 T3015 oasc.CoreContainer.<init> New CoreContainer 1828625227
[junit4] 2> 1231113 T3015 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/]
[junit4] 2> 1231115 T3015 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1231115 T3015 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 1231115 T3015 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1231115 T3015 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1231116 T3015 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1231116 T3015 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1231116 T3015 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1231116 T3015 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1231117 T3015 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1231117 T3015 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1231117 T3015 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1231118 T3015 oasc.CoreContainer.load Host Name:
[junit4] 2> 1231123 T3016 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1231124 T3016 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1231124 T3016 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1231125 T3016 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1231196 T3016 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 1231242 T3016 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1231244 T3016 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1231253 T3016 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1231427 T3016 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1231431 T3016 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1231433 T3016 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1231440 T3016 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1231442 T3016 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1231446 T3016 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1231447 T3016 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1231447 T3016 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1231447 T3016 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1231447 T3016 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1231448 T3016 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1231448 T3016 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/
[junit4] 2> 1231448 T3016 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1231449 T3016 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001/
[junit4] 2> 1231449 T3016 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f418e99
[junit4] 2> 1231450 T3016 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001
[junit4] 2> 1231450 T3016 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001/index/
[junit4] 2> 1231450 T3016 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 1231450 T3016 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001/index
[junit4] 2> 1231451 T3016 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=36, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.712718028270659]
[junit4] 2> 1231453 T3016 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@67906356 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3e1d44cb)),segFN=segments_1,generation=1}
[junit4] 2> 1231453 T3016 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1231454 T3016 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 1231457 T3016 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1231458 T3016 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1231458 T3016 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1231459 T3016 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1231459 T3016 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1231459 T3016 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1231459 T3016 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1231460 T3016 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1231460 T3016 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1231460 T3016 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1231461 T3016 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1231462 T3016 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1231462 T3016 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1231462 T3016 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1231463 T3016 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1231463 T3016 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1231464 T3016 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 1231464 T3016 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 1231465 T3016 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1231466 T3016 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1231466 T3016 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1231467 T3016 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1231467 T3016 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1231468 T3016 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1231469 T3016 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1231469 T3016 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1231470 T3016 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1231470 T3016 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1231470 T3016 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1231470 T3016 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1231471 T3016 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1231472 T3016 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1231472 T3016 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1231473 T3016 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 1231473 T3016 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 1231480 T3016 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1231480 T3016 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1231483 T3016 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1231483 T3016 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1231484 T3016 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=23.2470703125, floorSegmentMB=0.2568359375, forceMergeDeletesPctAllowed=12.522024497382688, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1231485 T3016 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@67906356 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3e1d44cb)),segFN=segments_1,generation=1}
[junit4] 2> 1231485 T3016 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1231485 T3016 oass.SolrIndexSearcher.<init> Opening Searcher@a7f300b[collection1] main
[junit4] 2> 1231486 T3016 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1231486 T3016 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1231486 T3016 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 1231487 T3016 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 1231487 T3016 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1231487 T3016 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1231496 T3016 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1231545 T3017 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a7f300b[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 1231552 T3016 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1231555 T3015 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 1231560 T3015 oas.SolrTestCaseJ4.setUp ###Starting testCoreAdminHandler
[junit4] 2> 1231592 T3015 oas.SolrTestCaseJ4.tearDown ###Ending testCoreAdminHandler
[junit4] 2> 1231598 T3015 oas.SolrTestCaseJ4.setUp ###Starting testOverriddenHandlers
[junit4] 2> 1231636 T3015 oas.SolrTestCaseJ4.tearDown ###Ending testOverriddenHandlers
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=InfoHandlerTest -Dtests.method=testOverriddenHandlers -Dtests.seed=D2E7427E993CE1FE -Dtests.slow=true -Dtests.locale=tr_TR -Dtests.timezone=America/Chicago -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.06s | InfoHandlerTest.testOverriddenHandlers <<<
[junit4] > Throwable #1: java.lang.Error: posix_spawn is not a supported process launch mechanism on this platform.
[junit4] > at __randomizedtesting.SeedInfo.seed([D2E7427E993CE1FE:34BC4E5960752E4A]:0)
[junit4] > at java.lang.UNIXProcess$1.run(UNIXProcess.java:105)
[junit4] > at java.lang.UNIXProcess$1.run(UNIXProcess.java:94)
[junit4] > at java.security.AccessController.doPrivileged(Native Method)
[junit4] > at java.lang.UNIXProcess.<clinit>(UNIXProcess.java:92)
[junit4] > at java.lang.ProcessImpl.start(ProcessImpl.java:130)
[junit4] > at java.lang.ProcessBuilder.start(ProcessBuilder.java:1029)
[junit4] > at java.lang.Runtime.exec(Runtime.java:620)
[junit4] > at java.lang.Runtime.exec(Runtime.java:450)
[junit4] > at java.lang.Runtime.exec(Runtime.java:347)
[junit4] > at org.apache.solr.handler.admin.SystemInfoHandler.execute(SystemInfoHandler.java:220)
[junit4] > at org.apache.solr.handler.admin.SystemInfoHandler.getSystemInfo(SystemInfoHandler.java:176)
[junit4] > at org.apache.solr.handler.admin.SystemInfoHandler.handleRequestBody(SystemInfoHandler.java:97)
[junit4] > at org.apache.solr.handler.admin.InfoHandlerTest$CountSystemInfoHandler.handleRequestBody(InfoHandlerTest.java:157)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4] > at org.apache.solr.handler.admin.InfoHandler.handleRequestBody(InfoHandler.java:88)
[junit4] > at org.apache.solr.handler.admin.InfoHandlerTest.handleRequest(InfoHandlerTest.java:168)
[junit4] > at org.apache.solr.handler.admin.InfoHandlerTest.testOverriddenHandlers(InfoHandlerTest.java:98)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1231651 T3015 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 1231652 T3015 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1828625227
[junit4] 2> 1231666 T3015 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1293f6ee
[junit4] 2> 1231684 T3015 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 1231685 T3015 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1231685 T3015 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1231685 T3015 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1231686 T3015 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1231687 T3015 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1231687 T3015 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001;done=false>>]
[junit4] 2> 1231687 T3015 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001
[junit4] 2> 1231693 T3015 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001/index;done=false>>]
[junit4] 2> 1231693 T3015 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001/init-core-data-001/index
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.handler.admin.InfoHandlerTest-D2E7427E993CE1FE-001
[junit4] 2> NOTE: test params are: codec=Lucene49: {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=tr_TR, timezone=America/Chicago
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_11 (64-bit)/cpus=2,threads=1,free=165261208,total=527433728
[junit4] 2> NOTE: All tests run in this JVM: [DefaultValueUpdateProcessorTest, BadCopyFieldTest, AnalyticsMergeStrategyTest, TestManagedStopFilterFactory, TestDocumentBuilder, TestChildDocTransformer, HdfsCollectionsAPIDistributedZkTest, SchemaVersionSpecificBehaviorTest, PreAnalyzedFieldTest, CustomCollectionTest, SolrRequestParserTest, BadIndexSchemaTest, SearchHandlerTest, TestSchemaResource, SolrIndexConfigTest, SolrCmdDistributorTest, AnalysisAfterCoreReloadTest, HdfsWriteToMultipleCollectionsTest, SpellCheckComponentTest, PathHierarchyTokenizerFactoryTest, TestValueSourceCache, PeerSyncTest, TestElisionMultitermQuery, TestPostingsSolrHighlighter, TestSolrQueryParser, TestRequestStatusCollectionAPI, DocExpirationUpdateProcessorFactoryTest, NotRequiredUniqueKeyTest, IndexSchemaTest, ZkControllerTest, SolrXmlInZkTest, AnalysisErrorHandlingTest, OverseerCollectionProcessorTest, TestUtils, TestIndexSearcher, SliceStateTest, TestDistribDocBasedVersion, DistribDocExpirationUpdateProcessorTest, JSONWriterTest, SolrTestCaseJ4Test, SyncSliceTest, DOMUtilTest, AbstractAnalyticsFacetTest, ExpressionTest, DistanceFunctionTest, DistributedSpellCheckComponentTest, MergeStrategyTest, TestJmxMonitoredMap, AddBlockUpdateTest, TestCloudManagedSchema, TriLevelCompositeIdRoutingTest, RAMDirectoryFactoryTest, DocValuesMultiTest, SampleTest, HighlighterTest, DistributedDebugComponentTest, SSLMigrationTest, FieldFacetTest, SolrInfoMBeanTest, TestFuzzyAnalyzedSuggestions, TestRTGBase, CurrencyFieldXmlFileTest, TestSolrXmlPersistence, TestRandomMergePolicy, FieldAnalysisRequestHandlerTest, TestAnalyzeInfixSuggestions, TestFiltering, NoFacetTest, XsltUpdateRequestHandlerTest, TestDistributedGrouping, TestFunctionQuery, TestLeaderElectionZkExpiry, OverseerStatusTest, DateRangeFieldTest, DirectUpdateHandlerOptimizeTest, CoreContainerCoreInitFailuresTest, TestWordDelimiterFilterFactory, TestFieldResource, SoftAutoCommitTest, AssignTest, TestCopyFieldCollectionResource, ReplicationFactorTest, IndexSchemaRuntimeFieldTest, InfoHandlerTest]
[junit4] Completed in 1.03s, 2 tests, 1 error <<< FAILURES!
[...truncated 176 lines...]
[junit4] Suite: org.apache.solr.MinimalSchemaTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001
[junit4] 2> 1988924 T4900 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 1988927 T4900 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 1988927 T4900 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1988928 T4900 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1988929 T4900 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1988929 T4900 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1989045 T4900 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 1989081 T4900 oasc.SolrConfig.<init> Loaded SolrConfig: solr/collection1/conf/solrconfig.xml
[junit4] 2> 1989086 T4900 oass.IndexSchema.readSchema Reading Solr Schema from solr/collection1/conf/schema-minimal.xml
[junit4] 2> 1989089 T4900 oass.IndexSchema.readSchema [null] Schema name=minimal
[junit4] 2> 1989093 T4900 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 1989097 T4900 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1989097 T4900 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr
[junit4] 2> 1989098 T4900 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/'
[junit4] 2> 1989144 T4900 oasc.CoreContainer.<init> New CoreContainer 559837187
[junit4] 2> 1989144 T4900 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/]
[junit4] 2> 1989145 T4900 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1989145 T4900 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 1989145 T4900 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1989145 T4900 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1989145 T4900 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1989146 T4900 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1989146 T4900 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1989146 T4900 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1989146 T4900 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1989150 T4900 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1989151 T4900 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1989151 T4900 oasc.CoreContainer.load Host Name:
[junit4] 2> 1989155 T4901 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 1989157 T4901 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 1989158 T4901 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1989158 T4901 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1989203 T4901 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 1989232 T4901 oasc.SolrConfig.<init> Loaded SolrConfig: solr/collection1/conf/solrconfig.xml
[junit4] 2> 1989234 T4901 oass.IndexSchema.readSchema Reading Solr Schema from solr/collection1/conf/schema-minimal.xml
[junit4] 2> 1989239 T4901 oass.IndexSchema.readSchema [collection1] Schema name=minimal
[junit4] 2> 1989260 T4901 oass.IndexSchema.readSchema WARN no uniqueKey specified in schema.
[junit4] 2> 1989260 T4901 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/
[junit4] 2> 1989261 T4901 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1989261 T4901 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001/
[junit4] 2> 1989262 T4901 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f418e99
[junit4] 2> 1989262 T4901 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001
[junit4] 2> 1989263 T4901 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001/index/
[junit4] 2> 1989263 T4901 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 1989265 T4901 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001/index
[junit4] 2> 1989265 T4901 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1421847245, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1989267 T4901 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@2bc37586 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e2dbdf))),segFN=segments_1,generation=1}
[junit4] 2> 1989267 T4901 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1989268 T4901 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 1989273 T4901 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1989273 T4901 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1989274 T4901 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1989274 T4901 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1989274 T4901 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1989275 T4901 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1989275 T4901 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1989275 T4901 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1989276 T4901 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1989277 T4901 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1989277 T4901 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1989278 T4901 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1989278 T4901 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1989279 T4901 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1989280 T4901 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1989280 T4901 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1989281 T4901 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 1989281 T4901 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 1989282 T4901 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1989283 T4901 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1989284 T4901 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 1989284 T4901 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1989285 T4901 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1989286 T4901 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1989286 T4901 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1989287 T4901 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1989287 T4901 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1989287 T4901 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1989288 T4901 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1989289 T4901 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1989289 T4901 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1989290 T4901 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1989291 T4901 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1989292 T4901 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 1989292 T4901 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 1989308 T4901 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1989309 T4901 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1989312 T4901 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1989312 T4901 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1989313 T4901 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1989314 T4901 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@2bc37586 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e2dbdf))),segFN=segments_1,generation=1}
[junit4] 2> 1989314 T4901 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1989314 T4901 oass.SolrIndexSearcher.<init> Opening Searcher@aa014d0[collection1] main
[junit4] 2> 1989314 T4901 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 1989315 T4901 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1989316 T4901 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 1989316 T4901 oasr.ManagedResourceStorage$JsonStorage.store Saved JSON object to path _rest_managed.json using InMemoryStorage
[junit4] 2> 1989316 T4901 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1989316 T4901 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1989325 T4901 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1989368 T4902 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@aa014d0[collection1] main{UninvertingDirectoryReader()}
[junit4] 2> 1989374 T4901 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1989378 T4900 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> ASYNC NEW_CORE C701 name=collection1 org.apache.solr.core.SolrCore@2e0653f7
[junit4] 2> 1989383 T4900 C701 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 3
[junit4] 2> 1989386 T4900 C701 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[(null)]} 0 1
[junit4] 2> 1989386 T4900 C701 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1989389 T4900 C701 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@2bc37586 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e2dbdf))),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RateLimitedDirectoryWrapper(RAMDirectory@2bc37586 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e2dbdf))),segFN=segments_2,generation=2}
[junit4] 2> 1989390 T4900 C701 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 1989391 T4900 C701 oass.SolrIndexSearcher.<init> Opening Searcher@17aa6d73[collection1] main
[junit4] 2> 1989392 T4900 C701 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1989393 T4902 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17aa6d73[collection1] main{UninvertingDirectoryReader(Uninverting(_0(5.0):C2))}
[junit4] 2> 1989394 T4900 C701 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4] 2> 1989394 T4900 C701 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1989395 T4900 C701 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1989395 T4900 C701 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 1989397 T4900 C701 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 1989398 T4900 C701 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {optimize=} 0 4
[junit4] 2> 1989402 T4900 oas.SolrTestCaseJ4.setUp ###Starting testAllConfiguredHandlers
[junit4] 2> 1989403 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=standard&omitHeader=false} hits=0 status=0 QTime=0
[junit4] 2> 1989404 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=&omitHeader=false} hits=0 status=0 QTime=0
[junit4] 2> 1989407 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=/admin/mbeans&omitHeader=false} status=0 QTime=2
[junit4] 2> 1989409 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=spellCheckCompRH&omitHeader=false} hits=0 status=0 QTime=2
[junit4] 2> 1989410 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=lazy&omitHeader=false} hits=0 status=0 QTime=1
[junit4] 2> 1989411 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=/admin/luke&omitHeader=false} status=0 QTime=1
[junit4] 2> 1989413 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=spellCheckWithWordbreak_Direct&omitHeader=false} hits=0 status=0 QTime=1
[junit4] 2> 1989414 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=tvrh&omitHeader=false} hits=0 status=0 QTime=1
[junit4] 2> 1989415 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=/get&omitHeader=false} status=0 QTime=1
[junit4] 2> 1989415 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=/admin/properties&omitHeader=false} status=0 QTime=0
[junit4] 2> 1989416 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=mltrh&omitHeader=false} hits=0 status=0 QTime=0
[junit4] 2> 1989421 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=/admin/threads&omitHeader=false} status=0 QTime=4
[junit4] 2> 1989423 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=foo:bar&qt=spellCheckCompRH1&omitHeader=false} hits=0 status=0 QTime=1
[junit4] 2> 1989425 T4900 oas.SolrTestCaseJ4.tearDown ###Ending testAllConfiguredHandlers
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MinimalSchemaTest -Dtests.method=testAllConfiguredHandlers -Dtests.seed=D2E7427E993CE1FE -Dtests.slow=true -Dtests.locale=en_NZ -Dtests.timezone=Pacific/Tarawa -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.03s | MinimalSchemaTest.testAllConfiguredHandlers <<<
[junit4] > Throwable #1: java.lang.NoClassDefFoundError: Could not initialize class java.lang.UNIXProcess
[junit4] > at __randomizedtesting.SeedInfo.seed([D2E7427E993CE1FE:3FF9F9441231E07A]:0)
[junit4] > at java.lang.ProcessImpl.start(ProcessImpl.java:130)
[junit4] > at java.lang.ProcessBuilder.start(ProcessBuilder.java:1029)
[junit4] > at java.lang.Runtime.exec(Runtime.java:620)
[junit4] > at java.lang.Runtime.exec(Runtime.java:450)
[junit4] > at java.lang.Runtime.exec(Runtime.java:347)
[junit4] > at org.apache.solr.handler.admin.SystemInfoHandler.execute(SystemInfoHandler.java:220)
[junit4] > at org.apache.solr.handler.admin.SystemInfoHandler.getSystemInfo(SystemInfoHandler.java:176)
[junit4] > at org.apache.solr.handler.admin.SystemInfoHandler.handleRequestBody(SystemInfoHandler.java:97)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:1981)
[junit4] > at org.apache.solr.util.TestHarness.query(TestHarness.java:295)
[junit4] > at org.apache.solr.util.TestHarness.query(TestHarness.java:278)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:693)
[junit4] > at org.apache.solr.MinimalSchemaTest.testAllConfiguredHandlers(MinimalSchemaTest.java:115)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1989435 T4900 oas.SolrTestCaseJ4.setUp ###Starting testLuke
[junit4] 2> 1989436 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={qt=/admin/luke} status=0 QTime=0
[junit4] 2> 1989439 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={qt=/admin/luke&show=schema} status=0 QTime=1
[junit4] 2> 1989440 T4900 oas.SolrTestCaseJ4.tearDown ###Ending testLuke
[junit4] 2> 1989448 T4900 oas.SolrTestCaseJ4.setUp ###Starting testSimpleQueries
[junit4] 2> 1989449 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=subject:Hoss&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 1989451 T4900 C701 oasc.SolrCore.execute [collection1] webapp=null path=null params={q=subject:Yonik&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 1989452 T4900 oas.SolrTestCaseJ4.tearDown ###Ending testSimpleQueries
[junit4] 2> 1989455 T4900 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 1989456 T4900 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=559837187
[junit4] 2> 1989456 T4900 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2e0653f7
[junit4] 2> 1989493 T4900 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=1,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=132,transaction_logs_total_number=1}
[junit4] 2> 1989493 T4900 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1989494 T4900 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1989494 T4900 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1989497 T4900 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1989497 T4900 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1989497 T4900 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001/index [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001/index;done=false>>]
[junit4] 2> 1989498 T4900 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001/index
[junit4] 2> 1989498 T4900 oasc.CachingDirectoryFactory.closeCacheValue looking to close /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001 [CachedDir<<refCount=0;path=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001;done=false>>]
[junit4] 2> 1989499 T4900 oasc.CachingDirectoryFactory.close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001/init-core-data-001
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.MinimalSchemaTest-D2E7427E993CE1FE-001
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=en_NZ, timezone=Pacific/Tarawa
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_11 (64-bit)/cpus=2,threads=1,free=295249880,total=526385152
[junit4] 2> NOTE: All tests run in this JVM: [DefaultValueUpdateProcessorTest, BadCopyFieldTest, AnalyticsMergeStrategyTest, TestManagedStopFilterFactory, TestDocumentBuilder, TestChildDocTransformer, HdfsCollectionsAPIDistributedZkTest, SchemaVersionSpecificBehaviorTest, PreAnalyzedFieldTest, CustomCollectionTest, SolrRequestParserTest, BadIndexSchemaTest, SearchHandlerTest, TestSchemaResource, SolrIndexConfigTest, SolrCmdDistributorTest, AnalysisAfterCoreReloadTest, HdfsWriteToMultipleCollectionsTest, SpellCheckComponentTest, PathHierarchyTokenizerFactoryTest, TestValueSourceCache, PeerSyncTest, TestElisionMultitermQuery, TestPostingsSolrHighlighter, TestSolrQueryParser, TestRequestStatusCollectionAPI, DocExpirationUpdateProcessorFactoryTest, NotRequiredUniqueKeyTest, IndexSchemaTest, ZkControllerTest, SolrXmlInZkTest, AnalysisErrorHandlingTest, OverseerCollectionProcessorTest, TestUtils, TestIndexSearcher, SliceStateTest, TestDistribDocBasedVersion, DistribDocExpirationUpdateProcessorTest, JSONWriterTest, SolrTestCaseJ4Test, SyncSliceTest, DOMUtilTest, AbstractAnalyticsFacetTest, ExpressionTest, DistanceFunctionTest, DistributedSpellCheckComponentTest, MergeStrategyTest, TestJmxMonitoredMap, AddBlockUpdateTest, TestCloudManagedSchema, TriLevelCompositeIdRoutingTest, RAMDirectoryFactoryTest, DocValuesMultiTest, SampleTest, HighlighterTest, DistributedDebugComponentTest, SSLMigrationTest, FieldFacetTest, SolrInfoMBeanTest, TestFuzzyAnalyzedSuggestions, TestRTGBase, CurrencyFieldXmlFileTest, TestSolrXmlPersistence, TestRandomMergePolicy, FieldAnalysisRequestHandlerTest, TestAnalyzeInfixSuggestions, TestFiltering, NoFacetTest, XsltUpdateRequestHandlerTest, TestDistributedGrouping, TestFunctionQuery, TestLeaderElectionZkExpiry, OverseerStatusTest, DateRangeFieldTest, DirectUpdateHandlerOptimizeTest, CoreContainerCoreInitFailuresTest, TestWordDelimiterFilterFactory, TestFieldResource, SoftAutoCommitTest, AssignTest, TestCopyFieldCollectionResource, ReplicationFactorTest, IndexSchemaRuntimeFieldTest, InfoHandlerTest, TestMergePolicyConfig, TestSolrCoreProperties, CSVRequestHandlerTest, ResponseLogComponentTest, TestInfoStreamLogging, HdfsBasicDistributedZkTest, SpatialFilterTest, SuggesterFSTTest, IndexBasedSpellCheckerTest, TestDynamicFieldCollectionResource, OverseerRolesTest, TestStressLucene, CurrencyFieldOpenExchangeTest, TestMiniSolrCloudCluster, DocValuesTest, PingRequestHandlerTest, TestAtomicUpdateErrorCases, TestPHPSerializedResponseWriter, TestHighFrequencyDictionaryFactory, TestSuggestSpellingConverter, TestAddFieldRealTimeGet, TestCollectionAPI, DirectUpdateHandlerTest, TestBinaryField, TestLMDirichletSimilarityFactory, PolyFieldTest, DateFieldTest, StandardRequestHandlerTest, TestRangeQuery, SolrIndexSplitterTest, TestPerFieldSimilarity, TestExpandComponent, TestZkChroot, LukeRequestHandlerTest, TestRemoteStreaming, TestIndexingPerformance, UnloadDistributedZkTest, TestSolrQueryParserDefaultOperatorResource, UpdateRequestProcessorFactoryTest, TestCollationField, AliasIntegrationTest, TestAnalyzedSuggestions, TestFieldTypeCollectionResource, TestNonDefinedSimilarityFactory, TestFieldTypeResource, CircularListTest, RankQueryTest, TestManagedResourceStorage, TestModifyConfFiles, TestSolrXmlPersistor, FullSolrCloudDistribCmdsTest, TestStressReorder, FieldMutatingUpdateProcessorTest, DistribCursorPagingTest, CacheHeaderTest, TestComponentsName, MinimalSchemaTest]
[junit4] Completed in 0.60s, 3 tests, 1 error <<< FAILURES!
[...truncated 54 lines...]
[junit4] Suite: org.apache.solr.cloud.HttpPartitionTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/init-core-data-001
[junit4] 2> 2154090 T5418 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 2154094 T5418 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 2154095 T5418 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2154096 T5419 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 2154197 T5418 oasc.ZkTestServer.run start zk server on port:50813
[junit4] 2> 2154199 T5418 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2154206 T5425 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@367542e6 name:ZooKeeperConnection Watcher:127.0.0.1:50813 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2154206 T5418 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2154206 T5418 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2154219 T5418 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2154223 T5427 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1155e71f name:ZooKeeperConnection Watcher:127.0.0.1:50813/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2154224 T5418 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2154224 T5418 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 2154232 T5418 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 2154241 T5418 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 2154248 T5418 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 2154254 T5418 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2154255 T5418 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2154267 T5418 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 2154268 T5418 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 2154276 T5418 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2154277 T5418 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2154285 T5418 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2154286 T5418 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2154292 T5418 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2154292 T5418 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 2154301 T5418 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2154301 T5418 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 2154311 T5418 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2154312 T5418 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 2154316 T5418 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2154317 T5418 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2154324 T5418 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2154325 T5418 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2154332 T5418 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2154333 T5418 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2154339 T5418 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2154339 T5418 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2156065 T5418 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2156114 T5418 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50817
[junit4] 2> 2156115 T5418 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2156115 T5418 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2156117 T5418 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-002
[junit4] 2> 2156117 T5418 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-002/'
[junit4] 2> 2156160 T5418 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-002/solr.xml
[junit4] 2> 2156182 T5418 oasc.CoreContainer.<init> New CoreContainer 1277878768
[junit4] 2> 2156182 T5418 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-002/]
[junit4] 2> 2156184 T5418 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2156184 T5418 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2156185 T5418 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2156185 T5418 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2156185 T5418 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2156186 T5418 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2156187 T5418 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2156187 T5418 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2156188 T5418 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2156190 T5418 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2156190 T5418 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2156191 T5418 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2156192 T5418 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50813/solr
[junit4] 2> 2156192 T5418 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2156194 T5418 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2156197 T5438 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@663c8df3 name:ZooKeeperConnection Watcher:127.0.0.1:50813 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2156197 T5418 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2156201 T5418 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2156205 T5440 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e93cfbb name:ZooKeeperConnection Watcher:127.0.0.1:50813/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2156205 T5418 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2156209 T5418 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 2156217 T5418 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 2156224 T5418 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 2156232 T5418 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 2156239 T5418 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 2156246 T5418 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2156251 T5418 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50816_
[junit4] 2> 2156253 T5418 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50816_
[junit4] 2> 2156260 T5418 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 2156265 T5418 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 2156276 T5418 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:50816_
[junit4] 2> 2156276 T5418 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 2156281 T5418 oasc.Overseer.start Overseer (id=92129511508017155-127.0.0.1:50816_-n_0000000000) starting
[junit4] 2> 2156287 T5418 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 2156311 T5442 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 2156313 T5418 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 2156320 T5418 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 2156324 T5418 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2156331 T5441 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 2156334 T5443 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 2156334 T5443 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2156336 T5440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2156336 T5443 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2156340 T5441 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2156342 T5441 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:50816",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:50816_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2156342 T5441 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 2156342 T5441 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2156346 T5440 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2156348 T5440 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> 2157337 T5443 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2157337 T5443 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 2157339 T5443 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2157340 T5443 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 2157343 T5443 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2157343 T5443 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-002/collection1/'
[junit4] 2> 2157344 T5443 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 2157345 T5443 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 2157345 T5443 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 2157400 T5443 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_5_0
[junit4] 2> 2157415 T5443 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2157420 T5443 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2157428 T5443 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2157574 T5443 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2157582 T5443 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2157583 T5443 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2157612 T5443 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2157618 T5443 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2157623 T5443 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2157626 T5443 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2157626 T5443 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2157627 T5443 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2157629 T5443 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2157630 T5443 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2157630 T5443 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 2157630 T5443 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2157631 T5443 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-001/control/data/
[junit4] 2> 2157631 T5443 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f418e99
[junit4] 2> 2157631 T5443 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-001/control/data
[junit4] 2> 2157632 T5443 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-001/control/data/index/
[junit4] 2> 2157632 T5443 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 2157632 T5443 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-001/control/data/index
[junit4] 2> 2157633 T5443 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2157634 T5443 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@43056cbe lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@39de36e0),segFN=segments_1,generation=1}
[junit4] 2> 2157635 T5443 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2157638 T5443 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2157638 T5443 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "de
[...truncated too long message...]
close Closing directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001/tempDir-004/c8n_1x3_lf_shard1_replica1/data
[junit4] 2> 2610161 T5482 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2610164 T5729 oasc.Overseer$ClusterStateUpdater.run Overseer Loop exiting : 127.0.0.1:50825_
[junit4] 2> 2610166 T5420 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1474f4d76c40008, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 2610167 T5731 oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read the data org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:277)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:274)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:317)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:289)
[junit4] 2>
[junit4] 2> 2610184 T5418 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4] 2> 2611666 T5482 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 2615172 T5418 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> 2615175 T5418 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:50813 50813
[junit4] 2> 2615204 T5419 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:50813 50813
[junit4] 2> 2615426 T5418 oasc.SocketProxy.close WARN Closing 0 connections to: http://127.0.0.1:50816/
[junit4] 2> 2615427 T5418 oasc.SocketProxy.close WARN Closing 3 connections to: http://127.0.0.1:50821/
[junit4] 2> 2615428 T5418 oasc.SocketProxy.close WARN Closing 0 connections to: http://127.0.0.1:50825/
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HttpPartitionTest -Dtests.method=testDistribSearch -Dtests.seed=D2E7427E993CE1FE -Dtests.slow=true -Dtests.locale=sr_RS_#Latn -Dtests.timezone=Asia/Tbilisi -Dtests.file.encoding=UTF-8
[junit4] ERROR 461s | HttpPartitionTest.testDistribSearch <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: No registered leader was found after waiting for 60000ms , collection: c8n_1x3_lf slice: shard1
[junit4] > at __randomizedtesting.SeedInfo.seed([D2E7427E993CE1FE:5301CC66EE6381C2]:0)
[junit4] > at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:567)
[junit4] > at org.apache.solr.cloud.HttpPartitionTest.testRf3WithLeaderFailover(HttpPartitionTest.java:371)
[junit4] > at org.apache.solr.cloud.HttpPartitionTest.doTest(HttpPartitionTest.java:150)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:863)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2615489 T5418 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/./temp/solr.cloud.HttpPartitionTest-D2E7427E993CE1FE-001
[junit4] 2> 461404 T5417 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene41, sim=DefaultSimilarity, locale=sr_RS_#Latn, timezone=Asia/Tbilisi
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_11 (64-bit)/cpus=2,threads=1,free=299993944,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [DefaultValueUpdateProcessorTest, BadCopyFieldTest, AnalyticsMergeStrategyTest, TestManagedStopFilterFactory, TestDocumentBuilder, TestChildDocTransformer, HdfsCollectionsAPIDistributedZkTest, SchemaVersionSpecificBehaviorTest, PreAnalyzedFieldTest, CustomCollectionTest, SolrRequestParserTest, BadIndexSchemaTest, SearchHandlerTest, TestSchemaResource, SolrIndexConfigTest, SolrCmdDistributorTest, AnalysisAfterCoreReloadTest, HdfsWriteToMultipleCollectionsTest, SpellCheckComponentTest, PathHierarchyTokenizerFactoryTest, TestValueSourceCache, PeerSyncTest, TestElisionMultitermQuery, TestPostingsSolrHighlighter, TestSolrQueryParser, TestRequestStatusCollectionAPI, DocExpirationUpdateProcessorFactoryTest, NotRequiredUniqueKeyTest, IndexSchemaTest, ZkControllerTest, SolrXmlInZkTest, AnalysisErrorHandlingTest, OverseerCollectionProcessorTest, TestUtils, TestIndexSearcher, SliceStateTest, TestDistribDocBasedVersion, DistribDocExpirationUpdateProcessorTest, JSONWriterTest, SolrTestCaseJ4Test, SyncSliceTest, DOMUtilTest, AbstractAnalyticsFacetTest, ExpressionTest, DistanceFunctionTest, DistributedSpellCheckComponentTest, MergeStrategyTest, TestJmxMonitoredMap, AddBlockUpdateTest, TestCloudManagedSchema, TriLevelCompositeIdRoutingTest, RAMDirectoryFactoryTest, DocValuesMultiTest, SampleTest, HighlighterTest, DistributedDebugComponentTest, SSLMigrationTest, FieldFacetTest, SolrInfoMBeanTest, TestFuzzyAnalyzedSuggestions, TestRTGBase, CurrencyFieldXmlFileTest, TestSolrXmlPersistence, TestRandomMergePolicy, FieldAnalysisRequestHandlerTest, TestAnalyzeInfixSuggestions, TestFiltering, NoFacetTest, XsltUpdateRequestHandlerTest, TestDistributedGrouping, TestFunctionQuery, TestLeaderElectionZkExpiry, OverseerStatusTest, DateRangeFieldTest, DirectUpdateHandlerOptimizeTest, CoreContainerCoreInitFailuresTest, TestWordDelimiterFilterFactory, TestFieldResource, SoftAutoCommitTest, AssignTest, TestCopyFieldCollectionResource, ReplicationFactorTest, IndexSchemaRuntimeFieldTest, InfoHandlerTest, TestMergePolicyConfig, TestSolrCoreProperties, CSVRequestHandlerTest, ResponseLogComponentTest, TestInfoStreamLogging, HdfsBasicDistributedZkTest, SpatialFilterTest, SuggesterFSTTest, IndexBasedSpellCheckerTest, TestDynamicFieldCollectionResource, OverseerRolesTest, TestStressLucene, CurrencyFieldOpenExchangeTest, TestMiniSolrCloudCluster, DocValuesTest, PingRequestHandlerTest, TestAtomicUpdateErrorCases, TestPHPSerializedResponseWriter, TestHighFrequencyDictionaryFactory, TestSuggestSpellingConverter, TestAddFieldRealTimeGet, TestCollectionAPI, DirectUpdateHandlerTest, TestBinaryField, TestLMDirichletSimilarityFactory, PolyFieldTest, DateFieldTest, StandardRequestHandlerTest, TestRangeQuery, SolrIndexSplitterTest, TestPerFieldSimilarity, TestExpandComponent, TestZkChroot, LukeRequestHandlerTest, TestRemoteStreaming, TestIndexingPerformance, UnloadDistributedZkTest, TestSolrQueryParserDefaultOperatorResource, UpdateRequestProcessorFactoryTest, TestCollationField, AliasIntegrationTest, TestAnalyzedSuggestions, TestFieldTypeCollectionResource, TestNonDefinedSimilarityFactory, TestFieldTypeResource, CircularListTest, RankQueryTest, TestManagedResourceStorage, TestModifyConfFiles, TestSolrXmlPersistor, FullSolrCloudDistribCmdsTest, TestStressReorder, FieldMutatingUpdateProcessorTest, DistribCursorPagingTest, CacheHeaderTest, TestComponentsName, MinimalSchemaTest, FieldFacetExtrasTest, PreAnalyzedUpdateProcessorTest, CachingDirectoryFactoryTest, MultiThreadedOCPTest, StatsComponentTest, TestManagedSchema, TestRecovery, TestJoin, ScriptEngineTest, TestCSVResponseWriter, TestCharFilters, TestFieldCollectionResource, EchoParamsTest, CoreAdminCreateDiscoverTest, SortByFunctionTest, HttpPartitionTest]
[junit4] Completed in 461.69s, 1 test, 1 error <<< FAILURES!
[...truncated 818 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:37: 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:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1298: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:922: There were test failures: 419 suites, 1712 tests, 3 errors, 43 ignored (16 assumptions)
Total time: 149 minutes 52 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any