You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/08/20 15:36:11 UTC
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.7.0_25) - Build # 7008
- Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/7008/
Java: 32bit/jdk1.7.0_25 -server -XX:+UseSerialGC
2 tests failed.
REGRESSION: org.apache.solr.cloud.ClusterStateUpdateTest.testCoreRegistration
Error Message:
Stack Trace:
org.apache.solr.common.cloud.ZooKeeperException:
at __randomizedtesting.SeedInfo.seed([81E253D7EDD52CF9:3F69357894AF22CC]:0)
at org.apache.solr.core.ZkContainer.registerInZk(ZkContainer.java:277)
at org.apache.solr.core.CoreContainer.registerCore(CoreContainer.java:452)
at org.apache.solr.core.CoreContainer.register(CoreContainer.java:471)
at org.apache.solr.cloud.ClusterStateUpdateTest.testCoreRegistration(ClusterStateUpdateTest.java:162)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
Caused by: org.apache.solr.common.SolrException: Error getting leader from zk for shard shard1
at org.apache.solr.cloud.ZkController.getLeader(ZkController.java:863)
at org.apache.solr.cloud.ZkController.register(ZkController.java:772)
at org.apache.solr.cloud.ZkController.register(ZkController.java:722)
at org.apache.solr.core.ZkContainer.registerInZk(ZkContainer.java:263)
... 43 more
Caused by: org.apache.solr.common.SolrException: No registered leader was found, collection:testcore slice:shard1
at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:487)
at org.apache.solr.common.cloud.ZkStateReader.getLeaderUrl(ZkStateReader.java:461)
at org.apache.solr.cloud.ZkController.getLeader(ZkController.java:842)
... 46 more
REGRESSION: org.apache.solr.schema.TestCloudManagedSchemaAddField.testDistribSearch
Error Message:
Max retry count 20 exceeded after 285 ms. QUERY FAILED: xpath=/response/lst[@name='responseHeader']/int[@name='status'][.='0'] request=/schema/fields/newfield9?wt=xml response=<?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">404</int> <int name="QTime">1</int> </lst> <lst name="error"> <str name="msg">Field 'newfield9' not found.</str> <int name="code">404</int> </lst> </response>
Stack Trace:
java.lang.AssertionError: Max retry count 20 exceeded after 285 ms. QUERY FAILED: xpath=/response/lst[@name='responseHeader']/int[@name='status'][.='0'] request=/schema/fields/newfield9?wt=xml response=<?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">404</int>
<int name="QTime">1</int>
</lst>
<lst name="error">
<str name="msg">Field 'newfield9' not found.</str>
<int name="code">404</int>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([81E253D7EDD52CF9:4DDCF9A8A4CC5]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.schema.TestCloudManagedSchemaAddField.doTest(TestCloudManagedSchemaAddField.java:127)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 9686 lines...]
[junit4] Suite: org.apache.solr.schema.TestCloudManagedSchemaAddField
[junit4] 2> 278697 T648 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 278700 T648 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-TestCloudManagedSchemaAddField-1377003550607
[junit4] 2> 278700 T648 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 278701 T649 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 278801 T648 oasc.ZkTestServer.run start zk server on port:38240
[junit4] 2> 278802 T648 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 279152 T655 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c946f name:ZooKeeperConnection Watcher:127.0.0.1:38240 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 279152 T648 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 279152 T648 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 279272 T648 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 279310 T657 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@92682d name:ZooKeeperConnection Watcher:127.0.0.1:38240/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 279311 T648 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 279312 T648 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 279400 T648 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 279428 T648 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 279456 T648 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 279483 T648 oasc.AbstractZkTestCase.putConfig put /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-managed-schema.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 279483 T648 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 279550 T648 oasc.AbstractZkTestCase.putConfig put /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 279550 T648 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 279629 T648 oasc.AbstractZkTestCase.putConfig put /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 279630 T648 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 279710 T648 oasc.AbstractZkTestCase.putConfig put /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 279711 T648 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 279745 T648 oasc.AbstractZkTestCase.putConfig put /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 279745 T648 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 279772 T648 oasc.AbstractZkTestCase.putConfig put /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 279773 T648 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 279866 T648 oasc.AbstractZkTestCase.putConfig put /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 279866 T648 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 279900 T648 oasc.AbstractZkTestCase.putConfig put /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 279901 T648 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 280008 T648 oasc.AbstractZkTestCase.putConfig put /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 280008 T648 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 280039 T648 oasc.AbstractZkTestCase.putConfig put /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 280039 T648 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 280160 T648 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 280163 T648 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58965
[junit4] 2> 280163 T648 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 280164 T648 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 280164 T648 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990
[junit4] 2> 280164 T648 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/'
[junit4] 2> 280173 T648 oasc.ConfigSolr.fromFile Loading container configuration from /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/solr.xml
[junit4] 2> 280215 T648 oasc.CoreContainer.<init> New CoreContainer 23682713
[junit4] 2> 280216 T648 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/]
[junit4] 2> 280217 T648 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 280217 T648 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 280218 T648 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 280218 T648 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 280218 T648 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 280219 T648 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 280219 T648 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 280219 T648 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 280220 T648 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 280220 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 280224 T648 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:38240/solr
[junit4] 2> 280233 T648 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 280234 T648 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 280272 T668 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3575ff name:ZooKeeperConnection Watcher:127.0.0.1:38240 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 280272 T648 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 280312 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 280317 T648 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 280349 T670 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@760ab8 name:ZooKeeperConnection Watcher:127.0.0.1:38240/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 280350 T648 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 280396 T648 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 280534 T648 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 280583 T648 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 280639 T648 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58965_
[junit4] 2> 280683 T648 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58965_
[junit4] 2> 280707 T648 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 280805 T648 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 280827 T648 oasc.Overseer.start Overseer (id=90243304692711427-127.0.0.1:58965_-n_0000000000) starting
[junit4] 2> 281209 T648 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 281295 T672 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 281295 T648 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 281335 T648 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 281372 T648 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 281380 T671 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 281384 T673 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 281384 T673 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 281416 T673 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 282886 T671 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 282890 T671 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":"1",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:58965_",
[junit4] 2> "base_url":"http://127.0.0.1:58965"}
[junit4] 2> 282890 T671 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 282896 T671 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 283005 T670 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> 283417 T673 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 283417 T673 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1
[junit4] 2> 283417 T673 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 283418 T673 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 283418 T673 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 283420 T673 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1/'
[junit4] 2> 283421 T673 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1/lib/README' to classloader
[junit4] 2> 283421 T673 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1/lib/classes/' to classloader
[junit4] 2> 283458 T673 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 283482 T673 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 283488 T673 oass.ManagedIndexSchemaFactory.create The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 283491 T673 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 283497 T673 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 283808 T673 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 283809 T673 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 283809 T673 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 283815 T673 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 283817 T673 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 283832 T673 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 283834 T673 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 283837 T673 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 283839 T673 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 283839 T673 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 283839 T673 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 283840 T673 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 283840 T673 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 284124 T673 oass.ManagedIndexSchema.persistManagedSchemaToZooKeeper Created and persisted managed schema znode at /configs/conf1/managed-schema
[junit4] 2> 284126 T673 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml.bak
[junit4] 2> 284289 T673 oass.ManagedIndexSchemaFactory.zkUgradeToManagedSchema After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/conf1/schema.xml to /configs/conf1/schema.xml.bak
[junit4] 2> 284290 T673 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 284290 T673 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1/, dataDir=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1/data/
[junit4] 2> 284290 T673 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 284298 T673 oasc.CachingDirectoryFactory.get return new directory for /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1/data
[junit4] 2> 284299 T673 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1/data/index/
[junit4] 2> 284299 T673 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 284299 T673 oasc.CachingDirectoryFactory.get return new directory for /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1/data/index
[junit4] 2> 284299 T673 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=84.515625, floorSegmentMB=0.6884765625, forceMergeDeletesPctAllowed=28.49321646487287, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.38545737948172587
[junit4] 2> 284480 T673 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@16d46ba; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 284481 T673 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 284482 T673 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 284482 T673 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 284482 T673 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 284482 T673 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 284483 T673 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 284483 T673 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 284483 T673 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 284485 T673 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 284487 T673 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 284487 T673 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 284488 T673 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=62.9248046875, floorSegmentMB=1.6123046875, forceMergeDeletesPctAllowed=21.67597592140337, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 284488 T673 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-controljetty-1377003551990/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@16d46ba; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 284488 T673 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 284489 T673 oass.SolrIndexSearcher.<init> Opening Searcher@de838d main
[junit4] 2> 284490 T673 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ...
[junit4] 2> 284493 T674 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@de838d main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 284493 T673 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 284493 T673 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:58965 collection:control_collection shard:shard1
[junit4] 2> 284496 T673 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 284500 T673 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 284917 T673 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 284995 T673 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 284995 T673 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 284996 T673 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:58965/collection1/
[junit4] 2> 284997 T673 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 284997 T673 oasc.SyncStrategy.syncToMe http://127.0.0.1:58965/collection1/ has no replicas
[junit4] 2> 284997 T673 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58965/collection1/ shard1
[junit4] 2> 284997 T673 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 286260 T671 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 286606 T670 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> 286738 T673 oasc.ZkController.register We are http://127.0.0.1:58965/collection1/ and leader is http://127.0.0.1:58965/collection1/
[junit4] 2> 286738 T673 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58965
[junit4] 2> 286738 T673 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 286739 T673 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 286739 T673 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 286773 T673 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 286774 T648 oass.SolrDispatchFilter.init user.dir=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 286774 T648 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 286775 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 286778 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4] 2> 286781 T648 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 286806 T677 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@988352 name:ZooKeeperConnection Watcher:127.0.0.1:38240/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 286806 T648 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 286808 T648 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 286812 T648 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4] 2> 286899 T648 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 286902 T648 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:38005
[junit4] 2> 286902 T648 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 286902 T648 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 286903 T648 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719
[junit4] 2> 286903 T648 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/'
[junit4] 2> 286912 T648 oasc.ConfigSolr.fromFile Loading container configuration from /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/solr.xml
[junit4] 2> 286950 T648 oasc.CoreContainer.<init> New CoreContainer 1570275
[junit4] 2> 286951 T648 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/]
[junit4] 2> 286952 T648 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 286952 T648 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 286952 T648 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 286953 T648 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 286953 T648 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 286953 T648 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 286953 T648 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 286954 T648 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 286954 T648 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 286954 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 286957 T648 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:38240/solr
[junit4] 2> 286958 T648 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 286958 T648 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 287006 T688 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1f7bf39 name:ZooKeeperConnection Watcher:127.0.0.1:38240 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 287006 T648 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 287061 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 287065 T648 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 287117 T690 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1132e2b name:ZooKeeperConnection Watcher:127.0.0.1:38240/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 287117 T648 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 287196 T648 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 288200 T648 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38005_
[junit4] 2> 288261 T648 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:38005_
[junit4] 2> 288263 T671 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 288318 T671 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":"1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"active",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:58965_",
[junit4] 2> "base_url":"http://127.0.0.1:58965"}
[junit4] 2> 288318 T677 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 288318 T690 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 288867 T677 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> 288868 T690 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> 288874 T691 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 288875 T691 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 288956 T691 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 288957 T670 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 288957 T670 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4] 2> 288960 T670 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> 290459 T671 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 290460 T671 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:38005_",
[junit4] 2> "base_url":"http://127.0.0.1:38005"}
[junit4] 2> 290460 T671 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2, shard3, shard4]
[junit4] 2> 290460 T671 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 291039 T690 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> 291039 T670 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> 291039 T677 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> 291957 T691 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 291957 T691 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1
[junit4] 2> 291957 T691 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 291958 T691 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 291958 T691 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 291960 T691 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1/'
[junit4] 2> 291960 T691 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1/lib/README' to classloader
[junit4] 2> 291960 T691 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1/lib/classes/' to classloader
[junit4] 2> 291994 T691 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 292018 T691 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 292020 T691 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema
[junit4] 2> 292025 T691 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 292346 T691 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 292346 T691 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 292347 T691 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 292351 T691 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 292352 T691 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 292356 T691 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 292360 T691 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 292370 T691 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 292371 T691 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 292371 T691 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 292371 T691 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 292372 T691 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 292372 T691 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 292375 T691 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 292375 T691 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1/, dataDir=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1/data/
[junit4] 2> 292375 T691 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 292376 T691 oasc.CachingDirectoryFactory.get return new directory for /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1/data
[junit4] 2> 292376 T691 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1/data/index/
[junit4] 2> 292376 T691 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 292376 T691 oasc.CachingDirectoryFactory.get return new directory for /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1/data/index
[junit4] 2> 292377 T691 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=84.515625, floorSegmentMB=0.6884765625, forceMergeDeletesPctAllowed=28.49321646487287, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.38545737948172587
[junit4] 2> 292565 T691 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e4c358; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 292565 T691 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 292566 T691 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 292566 T691 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 292567 T691 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 292567 T691 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 292567 T691 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 292567 T691 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 292567 T691 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 292569 T691 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 292571 T691 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 292571 T691 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 292572 T691 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=62.9248046875, floorSegmentMB=1.6123046875, forceMergeDeletesPctAllowed=21.67597592140337, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 292573 T691 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty1-1377003558719/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1e4c358; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 292573 T691 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 292573 T691 oass.SolrIndexSearcher.<init> Opening Searcher@1cb92d7 main
[junit4] 2> 292573 T691 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ...
[junit4] 2> 292575 T692 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cb92d7 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 292575 T691 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 292576 T691 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:38005 collection:collection1 shard:shard1
[junit4] 2> 292576 T691 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 292579 T691 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 292689 T691 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 292734 T691 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 292734 T691 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 292734 T691 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:38005/collection1/
[junit4] 2> 292735 T691 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 292735 T691 oasc.SyncStrategy.syncToMe http://127.0.0.1:38005/collection1/ has no replicas
[junit4] 2> 292735 T691 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:38005/collection1/ shard1
[junit4] 2> 292735 T691 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 294066 T671 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 294178 T670 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> 294178 T690 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> 294178 T677 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> 294213 T691 oasc.ZkController.register We are http://127.0.0.1:38005/collection1/ and leader is http://127.0.0.1:38005/collection1/
[junit4] 2> 294214 T691 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:38005
[junit4] 2> 294214 T691 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 294214 T691 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 294214 T691 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 294245 T691 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 294247 T648 oass.SolrDispatchFilter.init user.dir=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 294247 T648 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 294247 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 294310 T648 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 294313 T648 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:36768
[junit4] 2> 294313 T648 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 294314 T648 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 294314 T648 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156
[junit4] 2> 294314 T648 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/'
[junit4] 2> 294323 T648 oasc.ConfigSolr.fromFile Loading container configuration from /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/solr.xml
[junit4] 2> 294359 T648 oasc.CoreContainer.<init> New CoreContainer 17276972
[junit4] 2> 294360 T648 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/]
[junit4] 2> 294361 T648 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 294361 T648 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 294361 T648 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 294361 T648 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 294362 T648 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 294362 T648 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 294362 T648 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 294362 T648 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 294363 T648 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 294363 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 294366 T648 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:38240/solr
[junit4] 2> 294366 T648 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 294367 T648 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 294400 T704 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@78db10 name:ZooKeeperConnection Watcher:127.0.0.1:38240 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 294400 T648 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 294422 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 294425 T648 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 294450 T706 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c333a name:ZooKeeperConnection Watcher:127.0.0.1:38240/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 294450 T648 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 294497 T648 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 295500 T648 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36768_
[junit4] 2> 295522 T648 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36768_
[junit4] 2> 295567 T690 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4] 2> 295567 T677 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4] 2> 295568 T670 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 295568 T670 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 295569 T706 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 295570 T677 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 295571 T690 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 295615 T707 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 295615 T707 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 295656 T707 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 295747 T671 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 295748 T671 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"active",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:38005_",
[junit4] 2> "base_url":"http://127.0.0.1:38005"}
[junit4] 2> 295957 T671 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:36768_",
[junit4] 2> "base_url":"http://127.0.0.1:36768"}
[junit4] 2> 295957 T671 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4
[junit4] 2> 295957 T671 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard4
[junit4] 2> 296022 T670 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> 296022 T706 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> 296023 T677 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> 296023 T690 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> 296656 T707 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 296656 T707 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1
[junit4] 2> 296656 T707 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 296657 T707 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 296657 T707 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 296659 T707 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1/'
[junit4] 2> 296659 T707 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1/lib/README' to classloader
[junit4] 2> 296659 T707 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1/lib/classes/' to classloader
[junit4] 2> 296694 T707 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 296718 T707 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 296719 T707 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema
[junit4] 2> 296725 T707 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 297037 T707 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 297037 T707 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 297038 T707 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 297048 T707 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 297050 T707 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 297053 T707 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 297056 T707 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 297065 T707 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 297066 T707 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 297066 T707 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 297067 T707 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 297068 T707 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 297068 T707 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 297070 T707 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 297070 T707 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1/, dataDir=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1/data/
[junit4] 2> 297071 T707 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 297071 T707 oasc.CachingDirectoryFactory.get return new directory for /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1/data
[junit4] 2> 297071 T707 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1/data/index/
[junit4] 2> 297071 T707 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 297072 T707 oasc.CachingDirectoryFactory.get return new directory for /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1/data/index
[junit4] 2> 297072 T707 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=84.515625, floorSegmentMB=0.6884765625, forceMergeDeletesPctAllowed=28.49321646487287, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.38545737948172587
[junit4] 2> 297421 T707 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30907c; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 297421 T707 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 297422 T707 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 297422 T707 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 297423 T707 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 297424 T707 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 297424 T707 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 297424 T707 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 297425 T707 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 297430 T707 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 297433 T707 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 297433 T707 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 297433 T707 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=48, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=62.9248046875, floorSegmentMB=1.6123046875, forceMergeDeletesPctAllowed=21.67597592140337, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 297434 T707 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty2-1377003566156/collection1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30907c; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 297434 T707 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 297435 T707 oass.SolrIndexSearcher.<init> Opening Searcher@1f7069a main
[junit4] 2> 297435 T707 oass.ZkIndexSchemaReader.createSchemaWatcher Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema ...
[junit4] 2> 297437 T708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f7069a main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 297437 T707 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 297437 T707 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:36768 collection:collection1 shard:shard4
[junit4] 2> 297437 T707 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 297441 T707 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard4/election
[junit4] 2> 297600 T707 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard4
[junit4] 2> 297645 T707 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 297645 T707 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 297645 T707 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:36768/collection1/
[junit4] 2> 297646 T707 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 297646 T707 oasc.SyncStrategy.syncToMe http://127.0.0.1:36768/collection1/ has no replicas
[junit4] 2> 297646 T707 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:36768/collection1/ shard4
[junit4] 2> 297646 T707 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard4
[junit4] 2> 299125 T671 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 299500 T670 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> 299500 T677 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> 299500 T690 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> 299500 T706 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> 299522 T707 oasc.ZkController.register We are http://127.0.0.1:36768/collection1/ and leader is http://127.0.0.1:36768/collection1/
[junit4] 2> 299522 T707 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:36768
[junit4] 2> 299522 T707 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 299522 T707 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 299523 T707 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 299589 T707 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 299590 T648 oass.SolrDispatchFilter.init user.dir=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 299591 T648 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 299591 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 299656 T648 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 299659 T648 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:50884
[junit4] 2> 299659 T648 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 299659 T648 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 299660 T648 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty3-1377003571500
[junit4] 2> 299660 T648 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty3-1377003571500/'
[junit4] 2> 299669 T648 oasc.ConfigSolr.fromFile Loading container configuration from /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty3-1377003571500/solr.xml
[junit4] 2> 299710 T648 oasc.CoreContainer.<init> New CoreContainer 7645817
[junit4] 2> 299710 T648 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty3-1377003571500/]
[junit4] 2> 299711 T648 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 299711 T648 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 299711 T648 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 299712 T648 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 299712 T648 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 299712 T648 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 299712 T648 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 299713 T648 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 299713 T648 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 299713 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 299716 T648 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:38240/solr
[junit4] 2> 299716 T648 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 299717 T648 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 299739 T720 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@506ea2 name:ZooKeeperConnection Watcher:127.0.0.1:38240 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 299739 T648 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 299761 T648 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 299764 T648 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 299789 T722 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c5124d name:ZooKeeperConnection Watcher:127.0.0.1:38240/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 299789 T648 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 299835 T648 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 300839 T648 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50884_
[junit4] 2> 300894 T648 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50884_
[junit4] 2> 300917 T690 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 300918 T677 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 300918 T670 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 300919 T670 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4] 2> 300919 T722 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 300919 T706 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 300919 T706 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4] 2> 300920 T677 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 300920 T690 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 300949 T723 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 300949 T723 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 300972 T723 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 301114 T671 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 301115 T671 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":"shard4",
[junit4] 2> "roles":null,
[junit4] 2> "state":"active",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:36768_",
[junit4] 2> "base_url":"http://127.0.0.1:36768"}
[junit4] 2> 301162 T671 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=4 message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "numShards":"4",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:50884_",
[junit4] 2> "base_url":"http://127.0.0.1:50884"}
[junit4] 2> 301163 T671 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=4
[junit4] 2> 301163 T671 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 301378 T670 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 301378 T690 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 301378 T677 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 301378 T706 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 301378 T722 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 301973 T723 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 301973 T723 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty3-1377003571500/collection1
[junit4] 2> 301973 T723 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 301974 T723 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 301975 T723 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 301976 T723 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty3-1377003571500/collection1/'
[junit4] 2> 301977 T723 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty3-1377003571500/collection1/lib/README' to classloader
[junit4] 2> 301977 T723 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty3-1377003571500/collection1/lib/classes/' to classloader
[junit4] 2> 302004 T723 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 302034 T723 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 302036 T723 oass.IndexSchema.readSchema Reading Solr Schema from managed-schema
[junit4] 2> 302041 T723 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 302355 T723 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 302355 T723 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 302355 T723 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 302360 T723 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 302362 T723 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 302365 T723 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 302368 T723 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 302378 T723 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 302379 T723 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 302379 T723 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 302379 T723 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 302380 T723 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 302380 T723 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 302389 T723 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 302389 T723 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.schema.TestCloudManagedSchemaAddField-jetty3-1377003571500/collection1/, dataDir=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/bui
[...truncated too long message...]
but Solr cannot talk to ZK
[junit4] 2> 2488732 T2934 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 2488777 T2934 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4] 2> 2488777 T2934 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4] 2> 2488777 T2934 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 2488777 T2934 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:1663/solr/collection1/ shard1
[junit4] 2> 2488777 T2934 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 2488843 T2934 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 2488844 T2934 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2489710 T2889 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 2489711 T2889 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 2489766 T2889 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@177620c
[junit4] 2> 2489775 T2889 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2489776 T2889 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2489776 T2889 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2489776 T2889 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2489777 T2889 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2489777 T2889 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2489778 T2889 oasc.CachingDirectoryFactory.closeCacheValue looking to close /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-ClusterStateUpdateTest-1377004506828/data3 [CachedDir<<refCount=0;path=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-ClusterStateUpdateTest-1377004506828/data3;done=false>>]
[junit4] 2> 2489778 T2889 oasc.CachingDirectoryFactory.close Closing directory: /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-ClusterStateUpdateTest-1377004506828/data3
[junit4] 2> 2489778 T2889 oasc.CachingDirectoryFactory.closeCacheValue looking to close /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-ClusterStateUpdateTest-1377004506828/data3/index [CachedDir<<refCount=0;path=/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-ClusterStateUpdateTest-1377004506828/data3/index;done=false>>]
[junit4] 2> 2489778 T2889 oasc.CachingDirectoryFactory.close Closing directory: /var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-ClusterStateUpdateTest-1377004506828/data3/index
[junit4] 2> 2489779 T2937 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90243367359610892-127.0.0.1:1663_solr-n_0000000006) am no longer a leader.
[junit4] 2> 2489800 T2889 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:60378 60378
[junit4] 2> 2490172 T2889 oas.SolrTestCaseJ4.tearDown ###Ending testCoreRegistration
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ClusterStateUpdateTest -Dtests.method=testCoreRegistration -Dtests.seed=81E253D7EDD52CF9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_HN -Dtests.timezone=Europe/Athens -Dtests.file.encoding=US-ASCII
[junit4] ERROR 1255s J0 | ClusterStateUpdateTest.testCoreRegistration <<<
[junit4] > Throwable #1: org.apache.solr.common.cloud.ZooKeeperException:
[junit4] > at __randomizedtesting.SeedInfo.seed([81E253D7EDD52CF9:3F69357894AF22CC]:0)
[junit4] > at org.apache.solr.core.ZkContainer.registerInZk(ZkContainer.java:277)
[junit4] > at org.apache.solr.core.CoreContainer.registerCore(CoreContainer.java:452)
[junit4] > at org.apache.solr.core.CoreContainer.register(CoreContainer.java:471)
[junit4] > at org.apache.solr.cloud.ClusterStateUpdateTest.testCoreRegistration(ClusterStateUpdateTest.java:162)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] > Caused by: org.apache.solr.common.SolrException: Error getting leader from zk for shard shard1
[junit4] > at org.apache.solr.cloud.ZkController.getLeader(ZkController.java:863)
[junit4] > at org.apache.solr.cloud.ZkController.register(ZkController.java:772)
[junit4] > at org.apache.solr.cloud.ZkController.register(ZkController.java:722)
[junit4] > at org.apache.solr.core.ZkContainer.registerInZk(ZkContainer.java:263)
[junit4] > ... 43 more
[junit4] > Caused by: org.apache.solr.common.SolrException: No registered leader was found, collection:testcore slice:shard1
[junit4] > at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:487)
[junit4] > at org.apache.solr.common.cloud.ZkStateReader.getLeaderUrl(ZkStateReader.java:461)
[junit4] > at org.apache.solr.cloud.ZkController.getLeader(ZkController.java:842)
[junit4] > ... 46 more
[junit4] 2> 2490181 T2889 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 1255289 T2888 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> 2491099 T2934 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 2491099 T2934 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 2491100 T2934 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=es_HN, timezone=Europe/Athens
[junit4] 2> NOTE: Linux 3.2.0-51-generic i386/Oracle Corporation 1.7.0_25 (32-bit)/cpus=8,threads=1,free=105527576,total=227647488
[junit4] 2> NOTE: All tests run in this JVM: [SolrTestCaseJ4Test, TestPostingsSolrHighlighter, SimpleFacetsTest, CircularListTest, DocValuesTest, SliceStateTest, BlockDirectoryTest, TestJmxMonitoredMap, TestLRUCache, DOMUtilTest, AutoCommitTest, ShardRoutingTest, TestSystemIdResolver, TestWriterPerf, HdfsDirectoryTest, ZkControllerTest, TestShardHandlerFactory, TestManagedSchemaFieldResource, TestStressVersions, UpdateRequestProcessorFactoryTest, TestPerFieldSimilarity, DistributedQueryElevationComponentTest, SystemInfoHandlerTest, IndexSchemaTest, TermsComponentTest, TestLuceneMatchVersion, DocumentBuilderTest, MoreLikeThisHandlerTest, BasicFunctionalityTest, HdfsUnloadDistributedZkTest, UUIDFieldTest, FieldMutatingUpdateProcessorTest, TestCoreContainer, DateMathParserTest, TestSuggestSpellingConverter, AnalysisAfterCoreReloadTest, TestSearchPerf, SpatialFilterTest, TestRemoteStreaming, SyncSliceTest, StatelessScriptUpdateProcessorFactoryTest, SoftAutoCommitTest, SolrCoreTest, DirectUpdateHandlerOptimizeTest, TestQueryUtils, TestSolrQueryParserResource, TestExtendedDismaxParser, OverseerCollectionProcessorTest, ResourceLoaderTest, QueryParsingTest, TestDocumentBuilder, SolrInfoMBeanTest, TestImplicitCoreProperties, BadCopyFieldTest, ScriptEngineTest, TestFaceting, TestTrie, TestPHPSerializedResponseWriter, ParsingFieldUpdateProcessorsTest, TestJoin, RegexBoostProcessorTest, TestDocSet, TestMergePolicyConfig, TestConfig, EchoParamsTest, TestSolrXmlPersistence, TestReplicationHandler, TestReversedWildcardFilterFactory, ReturnFieldsTest, LegacyHTMLStripCharFilterTest, StressHdfsTest, SearchHandlerTest, SynonymTokenizerTest, LukeRequestHandlerTest, TestRandomMergePolicy, TestRandomDVFaceting, SchemaVersionSpecificBehaviorTest, BasicDistributedZkTest, UnloadDistributedZkTest, OverseerTest, BasicZkTest, ClusterStateUpdateTest]
[junit4] Completed on J0 in 1256.30s, 1 test, 1 error <<< FAILURES!
[...truncated 11 lines...]
BUILD FAILED
/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:395: The following error occurred while executing this line:
/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:375: The following error occurred while executing this line:
/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:181: The following error occurred while executing this line:
/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:450: The following error occurred while executing this line:
/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1233: The following error occurred while executing this line:
/var/lib/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:876: There were test failures: 323 suites, 1376 tests, 1 error, 1 failure, 35 ignored (6 assumptions)
Total time: 61 minutes 15 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_25 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure