You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2014/07/02 12:14:45 UTC
[JENKINS] Lucene-Solr-4.x-MacOSX (64bit/jdk1.8.0) - Build # 1648 -
Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/1648/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
REGRESSION: org.apache.solr.cloud.SyncSliceTest.testDistribSearch
Error Message:
No live SolrServers available to handle this request
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request
at __randomizedtesting.SeedInfo.seed([B4B05E6ECE38BF5D:3556D076B967DF61]:0)
at org.apache.solr.client.solrj.impl.LBHttpSolrServer.request(LBHttpSolrServer.java:317)
at org.apache.solr.client.solrj.impl.CloudSolrServer.request(CloudSolrServer.java:659)
at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:91)
at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:301)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1149)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1118)
at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:236)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
at sun.reflect.GeneratedMethodAccessor36.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.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:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.testDistribSearch
Error Message:
shard3 is not consistent. Got 113 from https://127.0.0.1:56699/t_n/collection1lastClient and got 109 from https://127.0.0.1:56717/t_n/collection1
Stack Trace:
java.lang.AssertionError: shard3 is not consistent. Got 113 from https://127.0.0.1:56699/t_n/collection1lastClient and got 109 from https://127.0.0.1:56717/t_n/collection1
at __randomizedtesting.SeedInfo.seed([B4B05E6ECE38BF5D:3556D076B967DF61]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1139)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1118)
at org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.doTest(ChaosMonkeySafeLeaderTest.java:150)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:865)
at sun.reflect.GeneratedMethodAccessor36.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.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:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11250 lines...]
[junit4] Suite: org.apache.solr.cloud.SyncSliceTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/init-core-data-001
[junit4] 2> 1783752 T6681 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 1783752 T6681 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /z/n
[junit4] 2> 1783756 T6681 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 1783758 T6681 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1783759 T6682 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1783859 T6681 oasc.ZkTestServer.run start zk server on port:56039
[junit4] 2> 1783861 T6681 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1783868 T6688 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@30df17a6 name:ZooKeeperConnection Watcher:127.0.0.1:56039 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1783869 T6681 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1783869 T6681 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1783882 T6681 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1783885 T6690 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3aab2ce name:ZooKeeperConnection Watcher:127.0.0.1:56039/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1783886 T6681 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1783886 T6681 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1783895 T6681 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1783903 T6681 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1783909 T6681 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1783917 T6681 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> 1783918 T6681 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1783932 T6681 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> 1783932 T6681 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1783941 T6681 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> 1783941 T6681 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1783948 T6681 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> 1783948 T6681 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1783955 T6681 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> 1783955 T6681 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1783963 T6681 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> 1783964 T6681 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1783972 T6681 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> 1783972 T6681 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1783979 T6681 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> 1783980 T6681 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1783987 T6681 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> 1783987 T6681 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1783993 T6681 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> 1783993 T6681 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1784000 T6681 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> 1784000 T6681 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1784010 T6681 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1784012 T6692 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3c30f545 name:ZooKeeperConnection Watcher:127.0.0.1:56039/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1784013 T6681 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1785664 T6681 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1785674 T6681 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 1785678 T6681 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:56043
[junit4] 2> 1785680 T6681 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1785680 T6681 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1785680 T6681 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-002
[junit4] 2> 1785681 T6681 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-002/'
[junit4] 2> 1785715 T6681 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/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-002/solr.xml
[junit4] 2> 1785738 T6681 oasc.CoreContainer.<init> New CoreContainer 931386516
[junit4] 2> 1785739 T6681 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-002/]
[junit4] 2> 1785739 T6681 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1785740 T6681 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1785741 T6681 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1785741 T6681 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1785741 T6681 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1785742 T6681 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1785742 T6681 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1785742 T6681 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1785743 T6681 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1785746 T6681 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1785746 T6681 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1785746 T6681 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1785747 T6681 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56039/solr
[junit4] 2> 1785747 T6681 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1785749 T6681 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1785754 T6703 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@710426c2 name:ZooKeeperConnection Watcher:127.0.0.1:56039 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1785755 T6681 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1785761 T6681 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1785771 T6705 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@41200556 name:ZooKeeperConnection Watcher:127.0.0.1:56039/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1785772 T6681 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1785776 T6681 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1785783 T6681 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1785790 T6681 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 1785796 T6681 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 1785805 T6681 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 1785821 T6681 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1785826 T6681 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56043_z%2Fn
[junit4] 2> 1785828 T6681 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56043_z%2Fn
[junit4] 2> 1785834 T6681 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 1785839 T6681 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1785849 T6681 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:56043_z%2Fn
[junit4] 2> 1785849 T6681 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1785854 T6681 oasc.Overseer.start Overseer (id=92031791005040644-127.0.0.1:56043_z%2Fn-n_0000000000) starting
[junit4] 2> 1785861 T6681 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1785890 T6707 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1785892 T6681 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1785897 T6681 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1785903 T6681 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1785911 T6706 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1785918 T6708 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 1785918 T6708 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1785921 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1785921 T6708 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1785925 T6706 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1785927 T6706 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:56043/z/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56043_z%2Fn",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 1785927 T6706 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 1785927 T6706 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1785934 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1785936 T6705 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> 1786924 T6708 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1786924 T6708 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 1786926 T6708 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1786926 T6708 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1786928 T6708 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1786929 T6708 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-002/collection1/'
[junit4] 2> 1786930 T6708 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 1786930 T6708 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 1786931 T6708 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 1786971 T6708 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 1786991 T6708 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1786994 T6708 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1786997 T6708 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1787147 T6708 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1787148 T6708 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1787149 T6708 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1787160 T6708 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1787161 T6708 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1787189 T6708 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1787192 T6708 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1787196 T6708 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1787199 T6708 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1787199 T6708 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1787200 T6708 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1787203 T6708 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1787203 T6708 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1787203 T6708 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 1787204 T6708 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1787204 T6708 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-002/collection1/, dataDir=./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/control/data/
[junit4] 2> 1787204 T6708 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6f2c4d59
[junit4] 2> 1787205 T6708 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/control/data
[junit4] 2> 1787205 T6708 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/control/data/index/
[junit4] 2> 1787206 T6708 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 1787206 T6708 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/control/data/index
[junit4] 2> 1787206 T6708 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=46.3017578125, floorSegmentMB=1.638671875, forceMergeDeletesPctAllowed=19.18533892526905, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1787208 T6708 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@76b151b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6dc2aa4a),segFN=segments_1,generation=1}
[junit4] 2> 1787208 T6708 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1787210 T6708 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1787212 T6708 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1787213 T6708 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1787213 T6708 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1787214 T6708 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1787214 T6708 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1787215 T6708 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1787215 T6708 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1787215 T6708 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1787215 T6708 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1787221 T6708 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1787221 T6708 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1787222 T6708 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1787222 T6708 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1787222 T6708 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1787223 T6708 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1787230 T6708 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1787234 T6708 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1787234 T6708 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1787235 T6708 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1787236 T6708 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@76b151b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6dc2aa4a),segFN=segments_1,generation=1}
[junit4] 2> 1787236 T6708 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1787236 T6708 oass.SolrIndexSearcher.<init> Opening Searcher@25cecbab[collection1] main
[junit4] 2> 1787236 T6708 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1787239 T6708 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1787239 T6708 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1787241 T6708 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1787241 T6708 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1787241 T6708 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1787243 T6708 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1787243 T6708 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1787243 T6708 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 1787244 T6708 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1787247 T6709 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@25cecbab[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1787247 T6708 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1787250 T6712 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56043/z/n collection:control_collection shard:shard1
[junit4] 2> 1787251 T6681 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1787252 T6681 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1787253 T6712 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1787258 T6681 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1787267 T6714 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6ecd6740 name:ZooKeeperConnection Watcher:127.0.0.1:56039/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1787268 T6681 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1787272 T6681 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1787273 T6712 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1787278 T6681 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1787278 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1787282 T6706 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1787283 T6712 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1787284 T6712 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C5700 name=collection1 org.apache.solr.core.SolrCore@646ab4e1 url=https://127.0.0.1:56043/z/n/collection1 node=127.0.0.1:56043_z%2Fn C5700_STATE=coll:control_collection core:collection1 props:{state=down, base_url=https://127.0.0.1:56043/z/n, core=collection1, node_name=127.0.0.1:56043_z%2Fn}
[junit4] 2> 1787284 T6712 C5700 P56043 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:56043/z/n/collection1/
[junit4] 2> 1787284 T6712 C5700 P56043 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1787285 T6712 C5700 P56043 oasc.SyncStrategy.syncToMe https://127.0.0.1:56043/z/n/collection1/ has no replicas
[junit4] 2> 1787285 T6712 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:56043/z/n/collection1/ shard1
[junit4] 2> 1787285 T6712 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1787296 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1787304 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1787325 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1787430 T6714 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> 1787430 T6705 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> 1787457 T6712 oasc.ZkController.register We are https://127.0.0.1:56043/z/n/collection1/ and leader is https://127.0.0.1:56043/z/n/collection1/
[junit4] 2> 1787457 T6712 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56043/z/n
[junit4] 2> 1787458 T6712 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1787458 T6712 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 1787458 T6712 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1787469 T6712 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1787469 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1787471 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1787471 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1787476 T6706 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1787477 T6706 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:56043/z/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56043_z%2Fn",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 1787483 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1787588 T6705 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> 1787588 T6714 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> 1788951 T6681 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 1788953 T6681 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1788959 T6681 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 1788961 T6681 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:56047
[junit4] 2> 1788965 T6681 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1788965 T6681 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1788965 T6681 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-003
[junit4] 2> 1788966 T6681 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-003/'
[junit4] 2> 1789008 T6681 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/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-003/solr.xml
[junit4] 2> 1789028 T6681 oasc.CoreContainer.<init> New CoreContainer 494149119
[junit4] 2> 1789029 T6681 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-003/]
[junit4] 2> 1789029 T6681 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1789030 T6681 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1789031 T6681 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1789031 T6681 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1789032 T6681 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1789032 T6681 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1789033 T6681 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1789033 T6681 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1789034 T6681 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1789039 T6681 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1789039 T6681 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1789039 T6681 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1789040 T6681 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56039/solr
[junit4] 2> 1789040 T6681 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1789043 T6681 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1789044 T6725 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@21f6ea78 name:ZooKeeperConnection Watcher:127.0.0.1:56039 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1789045 T6681 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1789051 T6681 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1789055 T6727 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6d86a6fe name:ZooKeeperConnection Watcher:127.0.0.1:56039/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1789055 T6681 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1789078 T6681 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1790092 T6681 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56047_z%2Fn
[junit4] 2> 1790100 T6681 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56047_z%2Fn
[junit4] 2> 1790115 T6727 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1790115 T6705 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1790115 T6714 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 1790149 T6728 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 1790150 T6728 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1790154 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1790154 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1790155 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1790155 T6728 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1790164 T6706 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1790168 T6706 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:56047/z/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56047_z%2Fn",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 1790168 T6706 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1]
[junit4] 2> 1790168 T6706 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1790184 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1790187 T6714 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> 1790188 T6705 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> 1790188 T6727 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> 1791157 T6728 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1791157 T6728 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1791159 T6728 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1791159 T6728 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1791162 T6728 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1791162 T6728 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-003/collection1/'
[junit4] 2> 1791164 T6728 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-003/collection1/lib/.svn/' to classloader
[junit4] 2> 1791164 T6728 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-003/collection1/lib/classes/' to classloader
[junit4] 2> 1791165 T6728 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-003/collection1/lib/README' to classloader
[junit4] 2> 1791210 T6728 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 1791227 T6728 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1791231 T6728 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1791239 T6728 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1791411 T6728 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1791411 T6728 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1791413 T6728 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1791426 T6728 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1791429 T6728 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1791467 T6728 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1791471 T6728 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1791476 T6728 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1791480 T6728 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1791480 T6728 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1791481 T6728 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1791485 T6728 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1791485 T6728 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1791485 T6728 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1791486 T6728 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1791486 T6728 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-003/collection1/, dataDir=./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/jetty1/
[junit4] 2> 1791487 T6728 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6f2c4d59
[junit4] 2> 1791488 T6728 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/jetty1
[junit4] 2> 1791488 T6728 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/jetty1/index/
[junit4] 2> 1791488 T6728 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 1791489 T6728 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/jetty1/index
[junit4] 2> 1791489 T6728 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=46.3017578125, floorSegmentMB=1.638671875, forceMergeDeletesPctAllowed=19.18533892526905, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1791490 T6728 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7c6e1f56 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f85cf13),segFN=segments_1,generation=1}
[junit4] 2> 1791491 T6728 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1791496 T6728 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1791497 T6728 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1791498 T6728 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1791498 T6728 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1791498 T6728 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1791499 T6728 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1791500 T6728 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1791501 T6728 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1791501 T6728 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1791502 T6728 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1791503 T6728 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1791504 T6728 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1791504 T6728 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1791505 T6728 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1791506 T6728 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1791507 T6728 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1791517 T6728 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1791521 T6728 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1791522 T6728 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1791523 T6728 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1791523 T6728 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7c6e1f56 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f85cf13),segFN=segments_1,generation=1}
[junit4] 2> 1791524 T6728 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1791524 T6728 oass.SolrIndexSearcher.<init> Opening Searcher@2d45c2c5[collection1] main
[junit4] 2> 1791524 T6728 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1791527 T6728 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1791528 T6728 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1791530 T6728 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1791530 T6728 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1791530 T6728 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1791532 T6728 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1791533 T6728 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1791533 T6728 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 1791533 T6728 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1791540 T6729 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2d45c2c5[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1791541 T6728 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1791544 T6732 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56047/z/n collection:collection1 shard:shard1
[junit4] 2> 1791545 T6681 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1791546 T6681 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1791547 T6732 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 1791566 T6732 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1791573 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1791577 T6732 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1791578 T6732 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C5701 name=collection1 org.apache.solr.core.SolrCore@4acd27d1 url=https://127.0.0.1:56047/z/n/collection1 node=127.0.0.1:56047_z%2Fn C5701_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:56047/z/n, core=collection1, node_name=127.0.0.1:56047_z%2Fn}
[junit4] 2> 1791578 T6732 C5701 P56047 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:56047/z/n/collection1/
[junit4] 2> 1791578 T6732 C5701 P56047 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1791579 T6732 C5701 P56047 oasc.SyncStrategy.syncToMe https://127.0.0.1:56047/z/n/collection1/ has no replicas
[junit4] 2> 1791579 T6732 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:56047/z/n/collection1/ shard1
[junit4] 2> 1791580 T6732 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 1791582 T6706 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1791600 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1791627 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1791656 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1791764 T6714 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> 1791765 T6705 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> 1791765 T6727 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> 1791806 T6732 oasc.ZkController.register We are https://127.0.0.1:56047/z/n/collection1/ and leader is https://127.0.0.1:56047/z/n/collection1/
[junit4] 2> 1791808 T6732 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56047/z/n
[junit4] 2> 1791808 T6732 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1791809 T6732 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 1791809 T6732 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1791815 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1791816 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1791816 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1791821 T6732 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1791824 T6706 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1791827 T6706 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"https://127.0.0.1:56047/z/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56047_z%2Fn",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 1791835 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1791941 T6714 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> 1791941 T6705 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> 1791941 T6727 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> 1793653 T6681 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 1793655 T6681 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1793663 T6681 oejus.SslContextFactory.doStart Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 2> 1793665 T6681 oejs.AbstractConnector.doStart Started SslSelectChannelConnector@127.0.0.1:56050
[junit4] 2> 1793668 T6681 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1793669 T6681 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1793669 T6681 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-004
[junit4] 2> 1793670 T6681 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-004/'
[junit4] 2> 1793746 T6681 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/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-004/solr.xml
[junit4] 2> 1793792 T6681 oasc.CoreContainer.<init> New CoreContainer 1785689083
[junit4] 2> 1793793 T6681 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-004/]
[junit4] 2> 1793794 T6681 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1793794 T6681 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1793794 T6681 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1793794 T6681 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1793795 T6681 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1793795 T6681 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1793796 T6681 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1793796 T6681 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1793797 T6681 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1793801 T6681 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1793802 T6681 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1793802 T6681 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1793803 T6681 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56039/solr
[junit4] 2> 1793803 T6681 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1793805 T6681 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1793809 T6743 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@730b9d33 name:ZooKeeperConnection Watcher:127.0.0.1:56039 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1793810 T6681 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1793816 T6681 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1793822 T6745 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@65f2ec3 name:ZooKeeperConnection Watcher:127.0.0.1:56039/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1793823 T6681 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1793841 T6681 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1794853 T6681 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56050_z%2Fn
[junit4] 2> 1794857 T6681 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56050_z%2Fn
[junit4] 2> 1794866 T6705 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1794866 T6727 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1794867 T6745 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1794868 T6714 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 1794882 T6746 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 1794882 T6746 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1794885 T6746 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1794885 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1794885 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1794886 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1794888 T6706 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1794890 T6706 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:56050/z/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56050_z%2Fn",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 1794891 T6706 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4] 2> 1794891 T6706 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1794898 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1794900 T6705 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> 1794901 T6714 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> 1794900 T6727 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> 1794900 T6745 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> 1795886 T6746 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1795886 T6746 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1795888 T6746 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1795888 T6746 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1795891 T6746 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1795892 T6746 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-004/collection1/'
[junit4] 2> 1795894 T6746 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-004/collection1/lib/.svn/' to classloader
[junit4] 2> 1795895 T6746 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 1795896 T6746 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 1795966 T6746 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_4_10
[junit4] 2> 1795991 T6746 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1795995 T6746 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1796006 T6746 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1796205 T6746 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1796206 T6746 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1796207 T6746 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1796216 T6746 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1796219 T6746 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1796259 T6746 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1796265 T6746 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1796272 T6746 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1796276 T6746 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1796277 T6746 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1796277 T6746 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1796281 T6746 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1796281 T6746 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1796282 T6746 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1796282 T6746 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1796282 T6746 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-004/collection1/, dataDir=./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/jetty2/
[junit4] 2> 1796283 T6746 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6f2c4d59
[junit4] 2> 1796283 T6746 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/jetty2
[junit4] 2> 1796284 T6746 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/jetty2/index/
[junit4] 2> 1796284 T6746 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 1796285 T6746 oasc.CachingDirectoryFactory.get return new directory for ./solr.cloud.SyncSliceTest-B4B05E6ECE38BF5D-001/tempDir-001/jetty2/index
[junit4] 2> 1796285 T6746 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=22, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=46.3017578125, floorSegmentMB=1.638671875, forceMergeDeletesPctAllowed=19.18533892526905, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1796286 T6746 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@67860996 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@573f8b07),segFN=segments_1,generation=1}
[junit4] 2> 1796286 T6746 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1796293 T6746 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1796294 T6746 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1796294 T6746 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1796294 T6746 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1796295 T6746 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1796295 T6746 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1796296 T6746 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1796296 T6746 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1796297 T6746 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1796297 T6746 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1796298 T6746 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1796298 T6746 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1796299 T6746 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1796300 T6746 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1796301 T6746 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1796302 T6746 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1796314 T6746 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1796318 T6746 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1796318 T6746 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1796319 T6746 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1796320 T6746 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@67860996 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@573f8b07),segFN=segments_1,generation=1}
[junit4] 2> 1796321 T6746 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1796321 T6746 oass.SolrIndexSearcher.<init> Opening Searcher@6672234f[collection1] main
[junit4] 2> 1796321 T6746 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1796325 T6746 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1796325 T6746 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1796327 T6746 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1796327 T6746 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1796327 T6746 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1796329 T6746 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1796329 T6746 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1796330 T6746 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 1796330 T6746 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1796334 T6747 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6672234f[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 1796337 T6746 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1796339 T6750 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:56050/z/n collection:collection1 shard:shard1
[junit4] 2> 1796340 T6681 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1796341 T6681 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1796348 T6750 oasc.ZkController.register We are https://127.0.0.1:56050/z/n/collection1/ and leader is https://127.0.0.1:56047/z/n/collection1/
[junit4] 2> 1796349 T6750 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:56050/z/n
[junit4] 2> 1796349 T6750 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 1796349 T6750 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C5702 name=collection1 org.apache.solr.core.SolrCore@2036af67 url=https://127.0.0.1:56050/z/n/collection1 node=127.0.0.1:56050_z%2Fn C5702_STATE=coll:collection1 core:collection1 props:{state=down, base_url=https://127.0.0.1:56050/z/n, core=collection1, node_name=127.0.0.1:56050_z%2Fn}
[junit4] 2> 1796350 T6751 C5702 P56050 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 1796351 T6751 C5702 P56050 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4] 2> 1796351 T6751 C5702 P56050 oasc.RecoveryStrategy.doRecovery Publishing state of core collection1 as recovering, leader is https://127.0.0.1:56047/z/n/collection1/ and I am https://127.0.0.1:56050/z/n/collection1/
[junit4] 2> 1796351 T6751 C5702 P56050 oasc.ZkController.publish publishing core=collection1 state=recovering collection=collection1
[junit4] 2> 1796352 T6751 C5702 P56050 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1796353 T6750 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1796359 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1796368 T6751 C5702 P56050 oasc.RecoveryStrategy.sendPrepRecoveryCmd Sending prep recovery command to https://127.0.0.1:56047/z/n; WaitForState: action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1%3A56050_z%252Fn&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true
[junit4] 2> 1796381 T6706 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1796401 T6706 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "base_url":"https://127.0.0.1:56050/z/n",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:56050_z%2Fn",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 1796433 T6705 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 1796438 T6714 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> 1796439 T6705 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> 1796444 T6727 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> 1796446 T6720 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1796446 T6745 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> 1796450 T6720 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1796453 T6720 oasha.CoreAdminHandler.handleWaitForStateAction Will wait a max of 183 seconds to see collection1 (shard1 of collection1) have state: recovering
[junit4] 2> 1796461 T6720 oasha.CoreAdminHandler.handleWaitForStateAction In WaitForState(recovering): collection=collection1, shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=recovering, localState=active, nodeName=127.0.0.1:56050_z%2Fn, coreNodeName=core_node2, onlyIfActiveCheckResult=false, nodeProps: core_node2:{"state":"recovering","base_url":"https://127.0.0.1:56050/z/n","core":"collection1","node_name":"127.0.0.1:56050_z%2Fn"}
[junit4] 2> 1796462 T6720 oasha.CoreAdminHandler.handleWaitForStateAction Waited coreNodeName: core_node2, state: recovering, checkLive: true, onlyIfLeader: true for: 0 seconds.
[junit4] 2> 1796462 T6720 oass.SolrDispatchFilter.handleAdminRequest [
[...truncated too long message...]
workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/solr.cloud.ChaosMonkeySafeLeaderTest-B4B05E6ECE38BF5D-001/tempDir-001/jetty12/index.20140702064604023
[junit4] 2> 2515051 T9087 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4] 2> 2515051 T9087 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 2515051 T9087 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 2515051 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515052 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515052 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515053 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515053 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515053 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515053 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515053 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515053 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515054 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515055 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515055 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515055 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515055 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 2515055 T9087 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-core/test/J0/./solr.cloud.ChaosMonkeySafeLeaderTest-B4B05E6ECE38BF5D-001
[junit4] 2> NOTE: test params are: codec=Lucene49: {rnd_b=PostingsFormat(name=MockRandom), _version_=MockVariableIntBlock(baseBlockSize=60), a_t=MockVariableIntBlock(baseBlockSize=60), a_si=PostingsFormat(name=FSTPulsing41), id=PostingsFormat(name=MockRandom)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=es_VE, timezone=America/Buenos_Aires
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_05 (64-bit)/cpus=2,threads=1,free=61286864,total=246308864
[junit4] 2> NOTE: All tests run in this JVM: [ChaosMonkeyNothingIsSafeTest, LeaderElectionIntegrationTest, TestSurroundQueryParser, ParsingFieldUpdateProcessorsTest, StandardRequestHandlerTest, TestManagedResourceStorage, XmlUpdateRequestHandlerTest, PeerSyncTest, SuggesterTSTTest, ClusterStateTest, TestStressReorder, NoCacheHeaderTest, TestCodecSupport, CoreAdminRequestStatusTest, SolrTestCaseJ4Test, BadComponentTest, HighlighterMaxOffsetTest, TestDefaultSearchFieldResource, TestFastWriter, HdfsSyncSliceTest, SystemInfoHandlerTest, SimpleFacetsTest, TermVectorComponentTest, OutputWriterTest, TestSchemaNameResource, RankQueryTest, DistribCursorPagingTest, ZkControllerTest, TestZkChroot, RecoveryZkTest, TestCollationFieldDocValues, TestRecoveryHdfs, SolrIndexSplitterTest, TestFuzzyAnalyzedSuggestions, TestShardHandlerFactory, SpatialFilterTest, TestCollapseQParserPlugin, TestTrie, TestSolrXml, DeleteReplicaTest, CursorPagingTest, TestPhraseSuggestions, DocumentBuilderTest, DocValuesMultiTest, PrimitiveFieldTypeTest, FullSolrCloudDistribCmdsTest, TestRequestStatusCollectionAPI, JSONWriterTest, RegexBoostProcessorTest, TestPHPSerializedResponseWriter, TestManagedResource, CacheHeaderTest, ZkSolrClientTest, TestMiniSolrCloudCluster, OverseerCollectionProcessorTest, TestReplicationHandler, CollectionsAPIAsyncDistributedZkTest, LegacyHTMLStripCharFilterTest, DistributedSuggestComponentTest, BasicFunctionalityTest, PreAnalyzedFieldTest, TestAnalyzeInfixSuggestions, BasicDistributedZkTest, TestChildDocTransformer, StatelessScriptUpdateProcessorFactoryTest, CopyFieldTest, TestFoldingMultitermQuery, JsonLoaderTest, TestNonDefinedSimilarityFactory, HdfsLockFactoryTest, BlockCacheTest, TestIBSimilarityFactory, AliasIntegrationTest, OverseerRolesTest, TestSerializedLuceneMatchVersion, SpellCheckComponentTest, TestJoin, SearchHandlerTest, DistributedExpandComponentTest, BinaryUpdateRequestHandlerTest, SignatureUpdateProcessorFactoryTest, TestStressRecovery, TestQuerySenderListener, TestDistribDocBasedVersion, TestNonNRTOpen, TestRemoteStreaming, TestSolrCoreProperties, OpenCloseCoreStressTest, RAMDirectoryFactoryTest, TestCoreDiscovery, PathHierarchyTokenizerFactoryTest, LukeRequestHandlerTest, TriLevelCompositeIdRoutingTest, RollingRestartTest, TestCollationField, TestOmitPositions, SpellingQueryConverterTest, PolyFieldTest, PluginInfoTest, BadCopyFieldTest, TestAddFieldRealTimeGet, TestSort, TestXIncludeConfig, TestCSVResponseWriter, CoreAdminHandlerTest, TestWordDelimiterFilterFactory, PingRequestHandlerTest, TestBinaryField, HardAutoCommitTest, FileBasedSpellCheckerTest, BasicZkTest, SOLR749Test, TestBinaryResponseWriter, TestAnalyzedSuggestions, TestInitQParser, TestFieldTypeCollectionResource, TestStressUserVersions, TestSchemaVersionResource, TestLuceneMatchVersion, TestElisionMultitermQuery, TestDistributedGrouping, TestFieldSortValues, TestQuerySenderNoQuery, SolrRequestParserTest, TestFieldCollectionResource, RequiredFieldsTest, HttpPartitionTest, TestCoreContainer, TestLRUCache, TestBlendedInfixSuggestions, TestDynamicFieldCollectionResource, ClusterStateUpdateTest, TestFiltering, URLClassifyProcessorTest, AnalyticsMergeStrategyTest, IndexSchemaRuntimeFieldTest, SyncSliceTest, AlternateDirectoryTest, TestReload, TestCollationKeyRangeQueries, CurrencyFieldOpenExchangeTest, TestNRTOpen, FieldAnalysisRequestHandlerTest, HdfsRecoveryZkTest, SimplePostToolTest, DistributedQueryComponentCustomSortTest, DateMathParserTest, TestFastLRUCache, TestSolrQueryParser, UUIDFieldTest, SoftAutoCommitTest, ShardRoutingCustomTest, DeleteInactiveReplicaTest, AsyncMigrateRouteKeyTest, TestReRankQParserPlugin, TestCloudManagedSchema, TestSolrXmlPersistence, DocValuesMissingTest, IndexBasedSpellCheckerTest, TestFaceting, TestUtils, TestDocSet, TestSchemaResource, BadIndexSchemaTest, WordBreakSolrSpellCheckerTest, SpellCheckCollatorTest, InfoHandlerTest, ScriptEngineTest, TestCloudManagedSchemaConcurrent, DOMUtilTest, DocExpirationUpdateProcessorFactoryTest, TestCharFilters, CoreMergeIndexesAdminHandlerTest, TestComplexPhraseQParserPlugin, CSVRequestHandlerTest, UnloadDistributedZkTest, TestSolrQueryParserDefaultOperatorResource, TestSweetSpotSimilarityFactory, SpellPossibilityIteratorTest, TestDFRSimilarityFactory, AssignTest, TestMaxScoreQueryParser, IndexSchemaTest, MultiTermTest, TestManagedSynonymFilterFactory, SolrCmdDistributorTest, CollectionsAPIDistributedZkTest, TestAtomicUpdateErrorCases, TestArbitraryIndexDir, TestInfoStreamLogging, TestSolrDeletionPolicy1, FileUtilsTest, TestRandomMergePolicy, ChaosMonkeySafeLeaderTest]
[junit4] Completed in 112.83s, 1 test, 1 failure <<< FAILURES!
[...truncated 625 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:1299: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:923: There were test failures: 404 suites, 1654 tests, 1 error, 1 failure, 43 ignored (16 assumptions)
Total time: 108 minutes 1 second
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any