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/03/30 04:21:38 UTC
[JENKINS] Lucene-Solr-4.x-MacOSX (64bit/jdk1.8.0) - Build # 1418 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/1418/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
REGRESSION: org.apache.solr.cloud.AliasIntegrationTest.testDistribSearch
Error Message:
deletealias the collection time out:180s
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: deletealias the collection time out:180s
at __randomizedtesting.SeedInfo.seed([B5F9757B37CBA1E3:341FFB634094C1DF]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:553)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:205)
at org.apache.solr.client.solrj.request.CollectionAdminRequest.process(CollectionAdminRequest.java:300)
at org.apache.solr.client.solrj.request.CollectionAdminRequest.deleteAlias(CollectionAdminRequest.java:512)
at org.apache.solr.cloud.AliasIntegrationTest.deleteAlias(AliasIntegrationTest.java:286)
at org.apache.solr.cloud.AliasIntegrationTest.doTest(AliasIntegrationTest.java:245)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
at sun.reflect.GeneratedMethodAccessor57.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:1617)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:826)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:862)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:876)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:783)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:443)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:835)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:771)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:782)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
at java.lang.Thread.run(Thread.java:744)
REGRESSION: org.apache.solr.cloud.OverseerStatusTest.testDistribSearch
Error Message:
overseerstatus the collection time out:180s
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: overseerstatus the collection time out:180s
at __randomizedtesting.SeedInfo.seed([B5F9757B37CBA1E3:341FFB634094C1DF]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.executeMethod(HttpSolrServer.java:553)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:209)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:205)
at org.apache.solr.cloud.OverseerStatusTest.invokeCollectionApi(OverseerStatusTest.java:121)
at org.apache.solr.cloud.OverseerStatusTest.doTest(OverseerStatusTest.java:96)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
at sun.reflect.GeneratedMethodAccessor57.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:1617)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:826)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:862)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:876)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:783)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:443)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:835)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:771)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:782)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
at java.lang.Thread.run(Thread.java:744)
Build Log:
[...truncated 11578 lines...]
[junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437
[junit4] 2> 2263121 T8635 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 2263122 T8635 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 2263126 T8635 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 2263127 T8635 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 2263127 T8636 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 2263229 T8635 oasc.ZkTestServer.run start zk server on port:55228
[junit4] 2> 2263232 T8635 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2263245 T8642 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13043521 name:ZooKeeperConnection Watcher:127.0.0.1:55228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2263246 T8635 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2263247 T8635 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 2263268 T8635 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2263277 T8644 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@293f59f4 name:ZooKeeperConnection Watcher:127.0.0.1:55228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2263278 T8635 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2263278 T8635 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 2263293 T8635 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 2263308 T8635 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 2263319 T8635 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 2263335 T8635 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2263336 T8635 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 2263359 T8635 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 2263361 T8635 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 2263378 T8635 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2263379 T8635 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2263394 T8635 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2263397 T8635 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 2263409 T8635 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2263410 T8635 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 2263416 T8635 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2263418 T8635 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 2263425 T8635 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2263425 T8635 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 2263430 T8635 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2263433 T8635 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 2263440 T8635 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2263440 T8635 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2263448 T8635 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2263449 T8635 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 2263454 T8635 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2263455 T8635 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 2265199 T8635 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2265283 T8635 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:?????
[junit4] 2> 2265284 T8635 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2265284 T8635 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2265285 T8635 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1396144665779
[junit4] 2> 2265285 T8635 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1396144665779/'
[junit4] 2> 2265323 T8635 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1396144665779/solr.xml
[junit4] 2> 2265346 T8635 oasc.CoreContainer.<init> New CoreContainer 29402267
[junit4] 2> 2265347 T8635 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1396144665779/]
[junit4] 2> 2265347 T8635 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2265348 T8635 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2265348 T8635 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2265349 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2265349 T8635 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2265350 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2265350 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2265351 T8635 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2265351 T8635 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2265352 T8635 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2265353 T8635 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2265353 T8635 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2265354 T8635 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55228/solr
[junit4] 2> 2265354 T8635 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2265356 T8635 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2265361 T8655 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@168ce34d name:ZooKeeperConnection Watcher:127.0.0.1:55228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2265361 T8635 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2265367 T8635 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2265370 T8657 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1de1830f name:ZooKeeperConnection Watcher:127.0.0.1:55228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2265371 T8635 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2265374 T8635 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 2265384 T8635 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 2265393 T8635 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 2265403 T8635 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 2265413 T8635 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 2265421 T8635 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 2265426 T8635 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55231_
[junit4] 2> 2265428 T8635 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55231_
[junit4] 2> 2265436 T8635 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 2265442 T8635 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 2265457 T8635 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:55231_
[junit4] 2> 2265457 T8635 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 2265466 T8635 oasc.Overseer.start Overseer (id=91497736794865667-127.0.0.1:55231_-n_0000000000) starting
[junit4] 2> 2265477 T8635 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 2265513 T8659 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 2265515 T8635 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 2265515 T8659 oasc.OverseerCollectionProcessor.prioritizeOverseerNodes prioritizing overseer nodes
[junit4] 2> 2265520 T8635 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 2265526 T8635 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2265536 T8658 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 2265538 T8660 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 2265539 T8660 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2265541 T8660 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2265543 T8658 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2265543 T8658 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:55231",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55231_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2265544 T8658 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 2265545 T8658 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2265550 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2265552 T8657 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> 2266543 T8660 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2266544 T8660 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 2266547 T8660 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2266548 T8660 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 2266554 T8660 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2266555 T8660 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1396144665779/collection1/'
[junit4] 2> 2266558 T8660 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1396144665779/collection1/lib/.svn/' to classloader
[junit4] 2> 2266559 T8660 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1396144665779/collection1/lib/classes/' to classloader
[junit4] 2> 2266561 T8660 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1396144665779/collection1/lib/README' to classloader
[junit4] 2> 2266647 T8660 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_48
[junit4] 2> 2266669 T8660 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2266676 T8660 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2266690 T8660 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2266869 T8660 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2266869 T8660 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2266871 T8660 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2266878 T8660 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2266881 T8660 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2266906 T8660 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2266911 T8660 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2266915 T8660 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2266917 T8660 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2266918 T8660 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2266918 T8660 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2266920 T8660 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2266921 T8660 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2266921 T8660 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 2266922 T8660 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2266922 T8660 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-controljetty-1396144665779/collection1/, dataDir=./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/control/data/
[junit4] 2> 2266922 T8660 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@deba1c2
[junit4] 2> 2266923 T8660 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/control/data
[junit4] 2> 2266923 T8660 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/control/data/index/
[junit4] 2> 2266923 T8660 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 2266924 T8660 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/control/data/index
[junit4] 2> 2266924 T8660 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=51.3232421875, floorSegmentMB=1.99609375, forceMergeDeletesPctAllowed=5.695813490710636, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2266925 T8660 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68a9460a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17fd67d1),segFN=segments_1,generation=1}
[junit4] 2> 2266925 T8660 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2266928 T8660 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2266928 T8660 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2266928 T8660 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2266929 T8660 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2266929 T8660 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2266929 T8660 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2266930 T8660 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2266931 T8660 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2266931 T8660 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2266932 T8660 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2266932 T8660 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2266932 T8660 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2266933 T8660 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2266933 T8660 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2266933 T8660 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2266934 T8660 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2266941 T8660 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2266944 T8660 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2266944 T8660 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2266946 T8660 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=82415518, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2266946 T8660 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68a9460a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17fd67d1),segFN=segments_1,generation=1}
[junit4] 2> 2266947 T8660 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2266947 T8660 oass.SolrIndexSearcher.<init> Opening Searcher@57b1196f[collection1] main
[junit4] 2> 2266952 T8661 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@57b1196f[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2266953 T8660 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2266955 T8664 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55231 collection:control_collection shard:shard1
[junit4] 2> 2266955 T8635 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2266955 T8635 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2266956 T8664 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 2266957 T8635 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2266960 T8666 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2afe4f3e name:ZooKeeperConnection Watcher:127.0.0.1:55228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2266960 T8635 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2266963 T8635 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2266971 T8635 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2266972 T8664 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2266975 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2266979 T8658 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2266981 T8664 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2266981 T8664 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4831 name=collection1 org.apache.solr.core.SolrCore@68f7d932 url=http://127.0.0.1:55231/collection1 node=127.0.0.1:55231_ C4831_STATE=coll:control_collection core:collection1 props:{state=down, base_url=http://127.0.0.1:55231, core=collection1, node_name=127.0.0.1:55231_}
[junit4] 2> 2266982 T8664 C4831 P55231 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55231/collection1/
[junit4] 2> 2266982 T8664 C4831 P55231 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2266983 T8664 C4831 P55231 oasc.SyncStrategy.syncToMe http://127.0.0.1:55231/collection1/ has no replicas
[junit4] 2> 2266983 T8664 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55231/collection1/ shard1
[junit4] 2> 2266984 T8664 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 2266990 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2267002 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2267011 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2267115 T8657 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> 2267118 T8666 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> 2267157 T8664 oasc.ZkController.register We are http://127.0.0.1:55231/collection1/ and leader is http://127.0.0.1:55231/collection1/
[junit4] 2> 2267157 T8664 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55231
[junit4] 2> 2267158 T8664 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2267158 T8664 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 2267159 T8664 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2267161 T8664 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2267162 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2267162 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2267162 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2267166 T8658 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2267169 T8658 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:55231",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55231_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2267173 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2267280 T8666 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> 2267282 T8657 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> 2268726 T8635 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 2268727 T8635 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2268732 T8635 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:?????
[junit4] 2> 2268733 T8635 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2268734 T8635 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2268734 T8635 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1396144669288
[junit4] 2> 2268734 T8635 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1396144669288/'
[junit4] 2> 2268775 T8635 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1396144669288/solr.xml
[junit4] 2> 2268787 T8635 oasc.CoreContainer.<init> New CoreContainer 305139929
[junit4] 2> 2268788 T8635 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1396144669288/]
[junit4] 2> 2268789 T8635 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2268789 T8635 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2268789 T8635 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2268790 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2268791 T8635 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2268791 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2268792 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2268792 T8635 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2268793 T8635 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2268793 T8635 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2268794 T8635 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2268794 T8635 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2268794 T8635 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55228/solr
[junit4] 2> 2268795 T8635 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2268796 T8635 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2268800 T8677 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f7c76d3 name:ZooKeeperConnection Watcher:127.0.0.1:55228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2268801 T8635 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2268807 T8635 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2268809 T8679 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6f15e607 name:ZooKeeperConnection Watcher:127.0.0.1:55228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2268810 T8635 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2268827 T8635 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2269837 T8635 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55235_
[junit4] 2> 2269843 T8635 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55235_
[junit4] 2> 2269855 T8657 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2269857 T8666 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2269864 T8679 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 2269880 T8680 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 2269881 T8680 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2269882 T8680 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2269883 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2269883 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2269883 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2269886 T8658 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2269888 T8658 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:55235",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55235_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2269889 T8658 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1]
[junit4] 2> 2269889 T8658 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2269893 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2269894 T8679 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> 2269895 T8657 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> 2269896 T8666 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> 2270884 T8680 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2270884 T8680 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2270887 T8680 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2270887 T8680 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2270891 T8680 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2270891 T8680 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1396144669288/collection1/'
[junit4] 2> 2270892 T8680 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1396144669288/collection1/lib/.svn/' to classloader
[junit4] 2> 2270893 T8680 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1396144669288/collection1/lib/classes/' to classloader
[junit4] 2> 2270893 T8680 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1396144669288/collection1/lib/README' to classloader
[junit4] 2> 2270942 T8680 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_48
[junit4] 2> 2270952 T8680 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2270955 T8680 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2270968 T8680 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2271110 T8680 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2271111 T8680 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2271111 T8680 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2271118 T8680 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2271120 T8680 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2271150 T8680 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2271155 T8680 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2271159 T8680 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2271162 T8680 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2271162 T8680 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2271163 T8680 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2271166 T8680 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2271166 T8680 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2271167 T8680 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 2271167 T8680 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2271167 T8680 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty1-1396144669288/collection1/, dataDir=./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/jetty1/
[junit4] 2> 2271168 T8680 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@deba1c2
[junit4] 2> 2271168 T8680 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/jetty1
[junit4] 2> 2271168 T8680 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/jetty1/index/
[junit4] 2> 2271169 T8680 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 2271169 T8680 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/jetty1/index
[junit4] 2> 2271169 T8680 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=51.3232421875, floorSegmentMB=1.99609375, forceMergeDeletesPctAllowed=5.695813490710636, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2271170 T8680 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@336ec52 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21593d43),segFN=segments_1,generation=1}
[junit4] 2> 2271170 T8680 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2271175 T8680 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2271175 T8680 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2271176 T8680 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2271176 T8680 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2271176 T8680 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2271177 T8680 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2271177 T8680 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2271178 T8680 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2271179 T8680 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2271179 T8680 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2271180 T8680 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2271180 T8680 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2271180 T8680 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2271181 T8680 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2271182 T8680 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2271182 T8680 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2271190 T8680 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2271192 T8680 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2271192 T8680 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2271202 T8680 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=82415518, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2271204 T8680 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@336ec52 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21593d43),segFN=segments_1,generation=1}
[junit4] 2> 2271204 T8680 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2271204 T8680 oass.SolrIndexSearcher.<init> Opening Searcher@12e767a0[collection1] main
[junit4] 2> 2271208 T8680 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2271210 T8681 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12e767a0[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2271213 T8684 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55235 collection:collection1 shard:shard1
[junit4] 2> 2271214 T8635 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2271214 T8635 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2271226 T8684 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 2271275 T8684 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2271288 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2271289 T8684 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 2271289 T8684 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C4832 name=collection1 org.apache.solr.core.SolrCore@7b8d961 url=http://127.0.0.1:55235/collection1 node=127.0.0.1:55235_ C4832_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:55235, core=collection1, node_name=127.0.0.1:55235_}
[junit4] 2> 2271289 T8684 C4832 P55235 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55235/collection1/
[junit4] 2> 2271289 T8684 C4832 P55235 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 2271290 T8684 C4832 P55235 oasc.SyncStrategy.syncToMe http://127.0.0.1:55235/collection1/ has no replicas
[junit4] 2> 2271290 T8684 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55235/collection1/ shard1
[junit4] 2> 2271290 T8684 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 2271294 T8658 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2271307 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2271327 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2271353 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2271458 T8657 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> 2271459 T8666 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> 2271459 T8679 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> 2271487 T8684 oasc.ZkController.register We are http://127.0.0.1:55235/collection1/ and leader is http://127.0.0.1:55235/collection1/
[junit4] 2> 2271487 T8684 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55235
[junit4] 2> 2271487 T8684 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 2271487 T8684 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 2271488 T8684 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2271491 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2271492 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2271492 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2271494 T8684 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2271495 T8658 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2271497 T8658 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:55235",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55235_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 2271504 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2271618 T8657 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> 2271618 T8666 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> 2271619 T8679 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> 2273172 T8635 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 2273172 T8635 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2273178 T8635 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:?????
[junit4] 2> 2273179 T8635 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2273179 T8635 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2273179 T8635 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1396144673532
[junit4] 2> 2273180 T8635 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1396144673532/'
[junit4] 2> 2273222 T8635 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1396144673532/solr.xml
[junit4] 2> 2273246 T8635 oasc.CoreContainer.<init> New CoreContainer 1145641791
[junit4] 2> 2273247 T8635 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1396144673532/]
[junit4] 2> 2273247 T8635 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2273248 T8635 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2273248 T8635 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2273248 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2273249 T8635 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2273249 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2273250 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2273251 T8635 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2273251 T8635 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2273251 T8635 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2273252 T8635 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2273253 T8635 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2273253 T8635 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55228/solr
[junit4] 2> 2273254 T8635 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2273255 T8635 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2273259 T8695 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@78cf3250 name:ZooKeeperConnection Watcher:127.0.0.1:55228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2273260 T8635 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2273264 T8635 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2273268 T8697 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7c66945b name:ZooKeeperConnection Watcher:127.0.0.1:55228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2273268 T8635 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2273283 T8635 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2274294 T8635 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55238_
[junit4] 2> 2274299 T8635 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55238_
[junit4] 2> 2274312 T8666 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2274314 T8697 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2274315 T8679 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2274315 T8657 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 2274346 T8698 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 2274347 T8698 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2274351 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2274352 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2274351 T8698 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 2274352 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2274363 T8658 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2274368 T8658 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:55238",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55238_",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 2274369 T8658 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4] 2> 2274369 T8658 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 2274384 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2274389 T8697 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> 2274390 T8666 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> 2274390 T8657 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> 2274390 T8679 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> 2275354 T8698 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 2275355 T8698 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 2275358 T8698 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 2275358 T8698 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 2275364 T8698 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 2275364 T8698 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1396144673532/collection1/'
[junit4] 2> 2275367 T8698 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1396144673532/collection1/lib/.svn/' to classloader
[junit4] 2> 2275368 T8698 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1396144673532/collection1/lib/classes/' to classloader
[junit4] 2> 2275370 T8698 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1396144673532/collection1/lib/README' to classloader
[junit4] 2> 2275416 T8698 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_48
[junit4] 2> 2275438 T8698 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2275443 T8698 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2275450 T8698 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2275608 T8698 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2275609 T8698 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 2275610 T8698 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 2275617 T8698 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 2275618 T8698 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2275653 T8698 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2275658 T8698 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 2275662 T8698 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2275666 T8698 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2275667 T8698 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2275667 T8698 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 2275671 T8698 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 2275671 T8698 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 2275672 T8698 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 2275672 T8698 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2275673 T8698 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty2-1396144673532/collection1/, dataDir=./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/jetty2/
[junit4] 2> 2275673 T8698 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@deba1c2
[junit4] 2> 2275674 T8698 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/jetty2
[junit4] 2> 2275674 T8698 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/jetty2/index/
[junit4] 2> 2275674 T8698 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 2275675 T8698 oasc.CachingDirectoryFactory.get return new directory for ./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-1396144665443/jetty2/index
[junit4] 2> 2275675 T8698 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=51.3232421875, floorSegmentMB=1.99609375, forceMergeDeletesPctAllowed=5.695813490710636, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2275676 T8698 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@ead359c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@250b8434),segFN=segments_1,generation=1}
[junit4] 2> 2275677 T8698 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2275682 T8698 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 2275682 T8698 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 2275683 T8698 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 2275683 T8698 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 2275683 T8698 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 2275683 T8698 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 2275684 T8698 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2275685 T8698 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 2275685 T8698 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2275685 T8698 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2275686 T8698 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 2275686 T8698 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 2275687 T8698 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2275687 T8698 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2275688 T8698 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 2275688 T8698 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 2275697 T8698 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2275700 T8698 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2275701 T8698 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2275701 T8698 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=82415518, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 2275702 T8698 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@ead359c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@250b8434),segFN=segments_1,generation=1}
[junit4] 2> 2275702 T8698 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2275702 T8698 oass.SolrIndexSearcher.<init> Opening Searcher@40c137f6[collection1] main
[junit4] 2> 2275707 T8698 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2275708 T8699 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@40c137f6[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2275709 T8702 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55238 collection:collection1 shard:shard1
[junit4] 2> 2275714 T8635 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 2275714 T8635 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2275732 T8702 oasc.ZkController.register We are http://127.0.0.1:55238/collection1/ and leader is http://127.0.0.1:55235/collection1/
[junit4] 2> 2275733 T8702 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55238
[junit4] 2> 2275733 T8702 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 2275734 T8702 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C4833 name=collection1 org.apache.solr.core.SolrCore@56d4d418 url=http://127.0.0.1:55238/collection1 node=127.0.0.1:55238_ C4833_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:55238, core=collection1, node_name=127.0.0.1:55238_}
[junit4] 2> 2275734 T8703 C4833 P55238 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 2275734 T8703 C4833 P55238 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4] 2> 2275735 T8703 C4833 P55238 oasc.ZkController.publish publishing core=collection1 state=recovering collection=collection1
[junit4] 2> 2275735 T8703 C4833 P55238 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2275748 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2275754 T8702 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2275754 T8658 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2275762 T8658 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "base_url":"http://127.0.0.1:55238",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:55238_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 2275765 T8675 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true
[junit4] 2> 2275767 T8675 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 2275770 T8675 oasha.CoreAdminHandler.handleWaitForStateAction Will wait a max of 183 seconds to see collection1 (shard1 of collection1) have state: recovering
[junit4] 2> 2275772 T8657 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2275884 T8666 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> 2275884 T8679 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> 2275885 T8657 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> 2275885 T8697 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> 2276772 T8675 oasha.CoreAdminHandler.handleWaitForStateAction Waited coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 2276773 T8675 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:55238_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node2&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=0 QTime=1008
[junit4] 2> 2277631 T8635 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 2277632 T8635 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2277637 T8635 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:?????
[junit4] 2> 2277638 T8635 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2277638 T8635 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2277638 T8635 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty3-1396144678032
[junit4] 2> 2277639 T8635 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty3-1396144678032/'
[junit4] 2> 2277678 T8635 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty3-1396144678032/solr.xml
[junit4] 2> 2277696 T8635 oasc.CoreContainer.<init> New CoreContainer 1331057714
[junit4] 2> 2277696 T8635 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solrtest-AliasIntegrationTest-1396144665437/org.apache.solr.cloud.AliasIntegrationTest-jetty3-1396144678032/]
[junit4] 2> 2277697 T8635 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 2277698 T8635 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 2277698 T8635 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2277698 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2277698 T8635 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2277699 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2277699 T8635 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2277700 T8635 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2277700 T8635 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2277701 T8635 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2277701 T8635 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2277702 T8635 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 2277702 T8635 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:55228/solr
[junit4] 2> 2277703 T8635 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 2277704 T8635 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2277707 T8715 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@63e3a6d name:ZooKeeperConnection Watcher:127.0.0.1:55228 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2277708 T8635 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2277715 T8635 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 2277717 T8717 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4b9bcc51 name:ZooKeeperConnection Watcher:127.0.0.1:55228/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 2277717 T8635 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 2277736 T8635 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 2278744 T8635 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55242_
[junit4] 2> 2278747 T8635 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55242_
[junit4] 2> 2278756 T8697 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2278756 T8717 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2278757 T8657 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2278756 T8666 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2278757 T8679 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 2278771 T8718 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 2278771 T8718 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> ASYNC NEW_CORE C4834 name=collection1 org.apache.solr.core.SolrCore@56d4d418 url=http://127.0.0.1:55238/collection1 node=127.0.0.1:55238_ C4834_STATE=coll:collection1 core:collection1 props:{state=recovering, base_url=http://127.0.0.1:55238, core=collection1, node_name=127.0.0.1:55238_}
[junit4] 2> 2278786 T8703 C4834 P55238 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:55235/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4] 2> 2278787 T8703 C4834 P55238 oasu.PeerSync.sync PeerSync: core=collec
[...truncated too long message...]
[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.getChildren(ZooKeeper.java:1472)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:259)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:256)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:256)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:92)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2>
[junit4] 2> NOTE: test params are: codec=Lucene46: {}, docValues:{}, sim=DefaultSimilarity, locale=no_NO_NY, timezone=Europe/Rome
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0 (64-bit)/cpus=2,threads=1,free=44730776,total=255930368
[junit4] 2> NOTE: All tests run in this JVM: [TestCollapseQParserPlugin, TestSolrQueryParserResource, SpellCheckCollatorTest, CSVRequestHandlerTest, TestPhraseSuggestions, TestRecovery, TestRemoteStreaming, TestLRUCache, MinimalSchemaTest, TestJmxMonitoredMap, DOMUtilTest, TestInfoStreamLogging, TestNRTOpen, TestElisionMultitermQuery, PrimUtilsTest, DistributedTermsComponentTest, SignatureUpdateProcessorFactoryTest, TestUtils, HdfsBasicDistributedZkTest, AlternateDirectoryTest, BadIndexSchemaTest, TermsComponentTest, QueryParsingTest, RequiredFieldsTest, HighlighterTest, XsltUpdateRequestHandlerTest, AutoCommitTest, TestReversedWildcardFilterFactory, PingRequestHandlerTest, TestValueSourceCache, TestSchemaVersionResource, TestDistributedMissingSort, AddBlockUpdateTest, MoreLikeThisHandlerTest, HighlighterConfigTest, TestManagedSchemaFieldResource, CoreAdminCreateDiscoverTest, SuggesterFSTTest, TestQuerySenderListener, LeaderElectionTest, TestRTGBase, TestCharFilters, TestJmxIntegration, TestDocSet, TestIndexSearcher, TestDistributedSearch, TestFieldTypeResource, FullSolrCloudDistribCmdsTest, TestStandardQParsers, TestFaceting, TestWriterPerf, DebugComponentTest, CurrencyFieldOpenExchangeTest, TestFileDictionaryLookup, PrimitiveFieldTypeTest, TestSchemaNameResource, TestMergePolicyConfig, AssignTest, HdfsDirectoryTest, TestNonNRTOpen, TestRangeQuery, SolrInfoMBeanTest, TestClassNameShortening, TestPartialUpdateDeduplication, CollectionsAPIDistributedZkTest, RemoteQueryErrorTest, OutputWriterTest, TestConfig, TestCoreContainer, TestSystemIdResolver, TestRandomMergePolicy, TestCloudManagedSchema, ConvertedLegacyTest, PluginInfoTest, DateFieldTest, MBeansHandlerTest, TestSort, ZkSolrClientTest, SolrIndexSplitterTest, TestSolrIndexConfig, CoreContainerCoreInitFailuresTest, TestFastOutputStream, TestSolrDeletionPolicy2, HdfsChaosMonkeySafeLeaderTest, AddSchemaFieldsUpdateProcessorFactoryTest, UniqFieldsUpdateProcessorFactoryTest, TestAnalyzeInfixSuggestions, TestBadConfig, URLClassifyProcessorTest, OpenExchangeRatesOrgProviderTest, HdfsRecoveryZkTest, TestDefaultSimilarityFactory, StandardRequestHandlerTest, TestIndexingPerformance, CoreMergeIndexesAdminHandlerTest, IndexBasedSpellCheckerTest, TestFunctionQuery, InfoHandlerTest, ReturnFieldsTest, BasicDistributedZkTest, ExternalFileFieldSortTest, TestCloudManagedSchemaAddField, TestComponentsName, DirectSolrSpellCheckerTest, QueryElevationComponentTest, TestCSVLoader, TestFieldSortValues, TestDFRSimilarityFactory, SliceStateTest, SampleTest, TestGroupingSearch, SpellingQueryConverterTest, SuggestComponentTest, MultiTermTest, PeerSyncTest, TestFastWriter, TestLMDirichletSimilarityFactory, SimpleFacetsTest, TestHighlightDedupGrouping, SchemaVersionSpecificBehaviorTest, ChangedSchemaMergeTest, OverseerTest, TestMiniSolrCloudCluster, TestArbitraryIndexDir, TestLazyCores, TestJoin, CursorMarkTest, SoftAutoCommitTest, PreAnalyzedUpdateProcessorTest, WordBreakSolrSpellCheckerTest, TestSolrXml, IndexSchemaTest, SuggesterTSTTest, TestCoreDiscovery, OverseerRolesTest, SOLR749Test, OverseerCollectionProcessorTest, TestLFUCache, TestPHPSerializedResponseWriter, TestDynamicFieldCollectionResource, TestSolr4Spatial, BlockCacheTest, LoggingHandlerTest, DocumentAnalysisRequestHandlerTest, EchoParamsTest, SystemInfoHandlerTest, SynonymTokenizerTest, ZkNodePropsTest, TestCSVResponseWriter, HdfsWriteToMultipleCollectionsTest, TestCollationFieldDocValues, SolrCoreCheckLockOnStartupTest, TestSearcherReuse, DocValuesMultiTest, TestSolrCoreProperties, TestOmitPositions, SpellCheckComponentTest, SolrRequestParserTest, TestSuggestSpellingConverter, DistributedQueryComponentCustomSortTest, TestCopyFieldCollectionResource, TestSolrXmlPersistence, ChaosMonkeyNothingIsSafeTest, TestStressVersions, TestFiltering, TestSolrQueryParserDefaultOperatorResource, JSONWriterTest, TestSurroundQueryParser, TestInitQParser, TestSchemaResource, TestPerFieldSimilarity, BasicFunctionalityTest, TestFastLRUCache, TestUpdate, TestXIncludeConfig, CachingDirectoryFactoryTest, SearchHandlerTest, UpdateRequestProcessorFactoryTest, ResourceLoaderTest, TestBlendedInfixSuggestions, TestCodecSupport, TestWordDelimiterFilterFactory, TestExpandComponent, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, RecoveryZkTest, TestReplicationHandler, ClusterStateUpdateTest, TestRandomFaceting, TestZkChroot, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, SliceStateUpdateTest, TestDistributedGrouping, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestStressReorder, TestMultiCoreConfBootstrap, TestReload, HardAutoCommitTest, SolrCoreTest, StatsComponentTest, SolrCmdDistributorTest, DirectUpdateHandlerTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, SimplePostToolTest, AnalysisAfterCoreReloadTest, TestExtendedDismaxParser, CoreAdminHandlerTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterTest, TestStressLucene, TestTrie, SpatialFilterTest, SuggesterWFSTTest, PolyFieldTest, NoCacheHeaderTest, TestPseudoReturnFields, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, DistanceFunctionTest, TestSolrDeletionPolicy1, CacheHeaderTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, TestQueryUtils, TestQueryTypes, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, SolrPluginUtilsTest, TestCollationField, TestAnalyzedSuggestions, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestBinaryResponseWriter, UpdateParamsTest, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, TestBinaryField, TestSearchPerf, NumericFieldsTest, TestFuzzyAnalyzedSuggestions, TestPostingsSolrHighlighter, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, SpellPossibilityIteratorTest, TestSweetSpotSimilarityFactory, TestNumberUtils, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, LegacyHTMLStripCharFilterTest, TimeZoneUtilsTest, ScriptEngineTest, DateMathParserTest, TestSolrXMLSerializer, PreAnalyzedFieldTest, RAMDirectoryFactoryTest, ClusterStateTest, TestSolrJ, TestDocumentBuilder, UUIDFieldTest, FileUtilsTest, CircularListTest, CursorPagingTest, SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, TestCollationKeyRangeQueries, AliasIntegrationTest, AsyncMigrateRouteKeyTest, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, CustomCollectionTest, DeleteInactiveReplicaTest, DeleteReplicaTest, DeleteShardTest, DistribCursorPagingTest, MigrateRouteKeyTest, OverseerStatusTest]
[junit4] Completed in 215.38s, 1 test, 1 error <<< FAILURES!
[...truncated 189 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:447: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/build.xml:45: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/extra-targets.xml:37: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build.xml:189: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/common-build.xml:496: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:1276: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:908: There were test failures: 380 suites, 1605 tests, 2 errors, 42 ignored (15 assumptions)
Total time: 97 minutes 30 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure