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/11 14:58:27 UTC
[JENKINS] Lucene-Solr-4.x-Linux (32bit/ibm-j9-jdk7) - Build # 6886
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/6886/
Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
1 tests failed.
FAILED: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch
Error Message:
Server at http://127.0.0.1:43631/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Server at http://127.0.0.1:43631/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
at __randomizedtesting.SeedInfo.seed([E2D13000F788C2CA:6337BE1880D7A2F6]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:385)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:196)
at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:88)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
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:780)
Build Log:
[...truncated 9872 lines...]
[junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4] 2> 292811 T1072 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 292816 T1072 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1376224797983
[junit4] 2> 292817 T1072 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 292818 T1073 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 292918 T1072 oasc.ZkTestServer.run start zk server on port:36724
[junit4] 2> 292919 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 292995 T1079 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d9b1b134 name:ZooKeeperConnection Watcher:127.0.0.1:36724 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 292995 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 292996 T1072 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 293000 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 293002 T1081 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@fdc6b395 name:ZooKeeperConnection Watcher:127.0.0.1:36724/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 293002 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 293003 T1072 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 293006 T1072 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 293008 T1072 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 293010 T1072 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 293014 T1072 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 293014 T1072 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 293018 T1072 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 293019 T1072 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 293022 T1072 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 293023 T1072 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 293025 T1072 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 293026 T1072 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 293028 T1072 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 293029 T1072 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 293031 T1072 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 293032 T1072 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 293034 T1072 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 293035 T1072 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 293038 T1072 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 293038 T1072 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 293041 T1072 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 293041 T1072 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 293044 T1072 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 293045 T1072 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 293125 T1072 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 293129 T1072 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:३२८१०
[junit4] 2> 293129 T1072 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 293130 T1072 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 293130 T1072 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376224798214
[junit4] 2> 293131 T1072 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376224798214/'
[junit4] 2> 293139 T1072 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376224798214/solr.xml
[junit4] 2> 293171 T1072 oasc.CoreContainer.<init> New CoreContainer 1384214797
[junit4] 2> 293172 T1072 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376224798214/]
[junit4] 2> 293173 T1072 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 293174 T1072 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 293175 T1072 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 293175 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 293176 T1072 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 293177 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 293177 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 293178 T1072 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 293178 T1072 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 293179 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 293183 T1072 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36724/solr
[junit4] 2> 293184 T1072 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 293185 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 293187 T1092 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@71775714 name:ZooKeeperConnection Watcher:127.0.0.1:36724 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 293187 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 293189 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 293193 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 293195 T1094 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@efd0b386 name:ZooKeeperConnection Watcher:127.0.0.1:36724/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 293195 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 293196 T1072 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 293200 T1072 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 293203 T1072 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 293205 T1072 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32810_
[junit4] 2> 293206 T1072 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:32810_
[junit4] 2> 293209 T1072 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 293213 T1072 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 293216 T1072 oasc.Overseer.start Overseer (id=90192268360810499-127.0.0.1:32810_-n_0000000000) starting
[junit4] 2> 293219 T1072 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 293222 T1096 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 293223 T1072 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 293225 T1072 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 293227 T1072 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 293230 T1095 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 293232 T1097 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 293232 T1097 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 293233 T1097 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 294733 T1095 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 294735 T1095 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:32810",
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:32810_",
[junit4] 2> "numShards":"1",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 294735 T1095 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 294735 T1095 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 294740 T1094 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> 295234 T1097 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 295234 T1097 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376224798214/collection1
[junit4] 2> 295235 T1097 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 295236 T1097 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 295236 T1097 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 295237 T1097 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376224798214/collection1/'
[junit4] 2> 295238 T1097 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376224798214/collection1/lib/README' to classloader
[junit4] 2> 295239 T1097 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376224798214/collection1/lib/classes/' to classloader
[junit4] 2> 295266 T1097 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 295298 T1097 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 295300 T1097 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 295306 T1097 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 295656 T1097 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 295657 T1097 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 295657 T1097 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 295663 T1097 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 295666 T1097 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 295678 T1097 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 295682 T1097 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 295686 T1097 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 295688 T1097 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 295688 T1097 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 295688 T1097 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 295689 T1097 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 295690 T1097 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 295690 T1097 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 295691 T1097 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376224798214/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/control/data/
[junit4] 2> 295691 T1097 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ac6f479
[junit4] 2> 295692 T1097 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/control/data
[junit4] 2> 295692 T1097 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/control/data/index/
[junit4] 2> 295692 T1097 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 295693 T1097 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/control/data/index
[junit4] 2> 295695 T1097 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@139bdfb9 lockFactory=org.apache.lucene.store.NativeFSLockFactory@35ce4ff3),segFN=segments_1,generation=1}
[junit4] 2> 295695 T1097 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 295697 T1097 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 295698 T1097 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 295698 T1097 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 295699 T1097 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 295699 T1097 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 295699 T1097 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 295700 T1097 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 295700 T1097 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 295701 T1097 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 295702 T1097 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 295704 T1097 oass.SolrIndexSearcher.<init> Opening Searcher@3af97958 main
[junit4] 2> 295705 T1097 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 295705 T1097 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 295708 T1098 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3af97958 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 295709 T1097 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 295709 T1097 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:32810 collection:control_collection shard:shard1
[junit4] 2> 295710 T1097 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 295714 T1097 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 295721 T1097 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 295723 T1097 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 295723 T1097 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 295724 T1097 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:32810/collection1/
[junit4] 2> 295724 T1097 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 295724 T1097 oasc.SyncStrategy.syncToMe http://127.0.0.1:32810/collection1/ has no replicas
[junit4] 2> 295725 T1097 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:32810/collection1/ shard1
[junit4] 2> 295725 T1097 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 296245 T1095 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 296252 T1094 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> 296282 T1097 oasc.ZkController.register We are http://127.0.0.1:32810/collection1/ and leader is http://127.0.0.1:32810/collection1/
[junit4] 2> 296282 T1097 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:32810
[junit4] 2> 296282 T1097 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 296283 T1097 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 296283 T1097 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 296286 T1097 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 296287 T1072 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 296288 T1072 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 296288 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 296292 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4] 2> 296294 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 296295 T1101 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8dcc3998 name:ZooKeeperConnection Watcher:127.0.0.1:36724/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 296296 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 296299 T1072 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 296301 T1072 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4] 2> 296381 T1072 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 296384 T1072 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:५५९१२
[junit4] 2> 296384 T1072 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 296385 T1072 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 296386 T1072 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376224801468
[junit4] 2> 296386 T1072 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376224801468/'
[junit4] 2> 296394 T1072 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376224801468/solr.xml
[junit4] 2> 296425 T1072 oasc.CoreContainer.<init> New CoreContainer 469753321
[junit4] 2> 296425 T1072 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376224801468/]
[junit4] 2> 296427 T1072 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 296427 T1072 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 296428 T1072 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 296428 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 296429 T1072 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 296429 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 296430 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 296431 T1072 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 296431 T1072 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 296432 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 296436 T1072 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36724/solr
[junit4] 2> 296437 T1072 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 296438 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 296439 T1112 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@87a24939 name:ZooKeeperConnection Watcher:127.0.0.1:36724 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 296440 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 296441 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 296446 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 296447 T1114 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@eea952f1 name:ZooKeeperConnection Watcher:127.0.0.1:36724/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 296447 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 296450 T1072 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 297454 T1072 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55912_
[junit4] 2> 297455 T1072 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:55912_
[junit4] 2> 297457 T1094 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> 297458 T1101 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 297458 T1114 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 297459 T1094 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 297463 T1115 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 297463 T1115 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 297465 T1115 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 297758 T1095 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 297759 T1095 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:32810",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:32810_",
[junit4] 2> "numShards":"1",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1"}
[junit4] 2> 297763 T1095 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:55912",
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:55912_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 297763 T1095 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 297764 T1095 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 297768 T1101 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> 297768 T1114 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> 297768 T1094 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> 298465 T1115 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 298466 T1115 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376224801468/collection1
[junit4] 2> 298466 T1115 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 298467 T1115 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 298468 T1115 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 298469 T1115 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376224801468/collection1/'
[junit4] 2> 298470 T1115 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376224801468/collection1/lib/README' to classloader
[junit4] 2> 298470 T1115 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376224801468/collection1/lib/classes/' to classloader
[junit4] 2> 298508 T1115 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 298540 T1115 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 298541 T1115 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 298548 T1115 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 298890 T1115 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 298890 T1115 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 298891 T1115 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 298896 T1115 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 298899 T1115 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 298911 T1115 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 298915 T1115 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 298918 T1115 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 298920 T1115 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 298920 T1115 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 298920 T1115 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 298921 T1115 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 298922 T1115 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 298922 T1115 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 298922 T1115 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376224801468/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty1/
[junit4] 2> 298923 T1115 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ac6f479
[junit4] 2> 298924 T1115 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty1
[junit4] 2> 298924 T1115 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty1/index/
[junit4] 2> 298924 T1115 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 298925 T1115 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty1/index
[junit4] 2> 298926 T1115 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@b89ca739 lockFactory=org.apache.lucene.store.NativeFSLockFactory@f7fc8664),segFN=segments_1,generation=1}
[junit4] 2> 298926 T1115 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 298928 T1115 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 298929 T1115 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 298929 T1115 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 298930 T1115 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 298930 T1115 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 298931 T1115 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 298931 T1115 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 298931 T1115 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 298932 T1115 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 298933 T1115 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 298935 T1115 oass.SolrIndexSearcher.<init> Opening Searcher@e7c147d3 main
[junit4] 2> 298936 T1115 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 298936 T1115 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 298939 T1116 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e7c147d3 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 298941 T1115 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 298941 T1115 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:55912 collection:collection1 shard:shard1
[junit4] 2> 298941 T1115 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 298945 T1115 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 298952 T1115 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 298954 T1115 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 298954 T1115 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 298955 T1115 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:55912/collection1/
[junit4] 2> 298955 T1115 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 298955 T1115 oasc.SyncStrategy.syncToMe http://127.0.0.1:55912/collection1/ has no replicas
[junit4] 2> 298956 T1115 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:55912/collection1/ shard1
[junit4] 2> 298956 T1115 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 299274 T1095 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 299282 T1101 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> 299282 T1094 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> 299283 T1114 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> 299313 T1115 oasc.ZkController.register We are http://127.0.0.1:55912/collection1/ and leader is http://127.0.0.1:55912/collection1/
[junit4] 2> 299313 T1115 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55912
[junit4] 2> 299313 T1115 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 299314 T1115 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 299314 T1115 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 299316 T1115 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 299317 T1072 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 299318 T1072 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 299318 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 299403 T1072 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 299405 T1072 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:४३६३१
[junit4] 2> 299406 T1072 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 299407 T1072 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 299407 T1072 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376224804489
[junit4] 2> 299408 T1072 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376224804489/'
[junit4] 2> 299416 T1072 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376224804489/solr.xml
[junit4] 2> 299449 T1072 oasc.CoreContainer.<init> New CoreContainer 1604480893
[junit4] 2> 299450 T1072 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376224804489/]
[junit4] 2> 299451 T1072 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 299452 T1072 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 299452 T1072 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 299453 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 299454 T1072 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 299454 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 299455 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 299455 T1072 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 299456 T1072 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 299457 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 299461 T1072 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36724/solr
[junit4] 2> 299462 T1072 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 299463 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 299464 T1128 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@cc4452dd name:ZooKeeperConnection Watcher:127.0.0.1:36724 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 299465 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 299466 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 299471 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 299472 T1130 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8b2cc047 name:ZooKeeperConnection Watcher:127.0.0.1:36724/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 299473 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 299479 T1072 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 300483 T1072 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43631_
[junit4] 2> 300485 T1072 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:43631_
[junit4] 2> 300488 T1094 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> 300488 T1101 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 300489 T1130 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 300489 T1114 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 300489 T1101 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> 300489 T1114 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> 300490 T1094 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 300494 T1131 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 300494 T1131 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 300496 T1131 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 300788 T1095 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 300790 T1095 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:55912",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:55912_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1"}
[junit4] 2> 300794 T1095 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:43631",
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "node_name":"127.0.0.1:43631_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 300794 T1095 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 300795 T1095 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 300798 T1130 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> 300798 T1101 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> 300798 T1094 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> 300798 T1114 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> 301496 T1131 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 301497 T1131 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376224804489/collection1
[junit4] 2> 301497 T1131 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 301498 T1131 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 301498 T1131 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 301499 T1131 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376224804489/collection1/'
[junit4] 2> 301500 T1131 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376224804489/collection1/lib/README' to classloader
[junit4] 2> 301501 T1131 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376224804489/collection1/lib/classes/' to classloader
[junit4] 2> 301527 T1131 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 301563 T1131 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 301564 T1131 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 301571 T1131 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 301909 T1131 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 301910 T1131 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 301910 T1131 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 301916 T1131 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 301919 T1131 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 301930 T1131 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 301934 T1131 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 301938 T1131 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 301939 T1131 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 301940 T1131 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 301940 T1131 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 301941 T1131 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 301941 T1131 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 301942 T1131 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 301942 T1131 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376224804489/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty2/
[junit4] 2> 301943 T1131 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ac6f479
[junit4] 2> 301943 T1131 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty2
[junit4] 2> 301944 T1131 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty2/index/
[junit4] 2> 301944 T1131 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 301944 T1131 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty2/index
[junit4] 2> 301946 T1131 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@504e8b74 lockFactory=org.apache.lucene.store.NativeFSLockFactory@a58fbf97),segFN=segments_1,generation=1}
[junit4] 2> 301946 T1131 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 301948 T1131 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 301949 T1131 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 301949 T1131 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 301950 T1131 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 301950 T1131 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 301950 T1131 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 301951 T1131 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 301951 T1131 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 301952 T1131 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 301953 T1131 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 301955 T1131 oass.SolrIndexSearcher.<init> Opening Searcher@6f1066d1 main
[junit4] 2> 301956 T1131 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 301956 T1131 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 301960 T1132 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6f1066d1 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 301961 T1131 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 301961 T1131 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:43631 collection:collection1 shard:shard2
[junit4] 2> 301962 T1131 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 301966 T1131 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 301971 T1131 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 301973 T1131 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 301974 T1131 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 301974 T1131 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:43631/collection1/
[junit4] 2> 301974 T1131 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 301975 T1131 oasc.SyncStrategy.syncToMe http://127.0.0.1:43631/collection1/ has no replicas
[junit4] 2> 301975 T1131 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:43631/collection1/ shard2
[junit4] 2> 301975 T1131 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 302304 T1095 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 302311 T1130 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> 302311 T1101 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> 302312 T1094 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> 302311 T1114 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> 302332 T1131 oasc.ZkController.register We are http://127.0.0.1:43631/collection1/ and leader is http://127.0.0.1:43631/collection1/
[junit4] 2> 302332 T1131 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:43631
[junit4] 2> 302332 T1131 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 302332 T1131 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 302333 T1131 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 302335 T1131 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 302336 T1072 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 302336 T1072 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 302337 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 302418 T1072 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 302420 T1072 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:४८८८२
[junit4] 2> 302421 T1072 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 302422 T1072 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 302423 T1072 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376224807507
[junit4] 2> 302423 T1072 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376224807507/'
[junit4] 2> 302431 T1072 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376224807507/solr.xml
[junit4] 2> 302461 T1072 oasc.CoreContainer.<init> New CoreContainer -1880674778
[junit4] 2> 302462 T1072 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376224807507/]
[junit4] 2> 302463 T1072 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 302464 T1072 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 302465 T1072 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 302465 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 302466 T1072 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 302466 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 302467 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 302468 T1072 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 302468 T1072 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 302469 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 302473 T1072 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36724/solr
[junit4] 2> 302474 T1072 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 302475 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 302476 T1144 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ef213aa2 name:ZooKeeperConnection Watcher:127.0.0.1:36724 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 302476 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 302478 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 302483 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 302484 T1146 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@77a3a0e1 name:ZooKeeperConnection Watcher:127.0.0.1:36724/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 302484 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 302488 T1072 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 303491 T1072 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48882_
[junit4] 2> 303492 T1072 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48882_
[junit4] 2> 303494 T1094 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> 303495 T1130 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 303495 T1146 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 303495 T1101 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 303496 T1114 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 303496 T1130 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> 303497 T1114 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> 303496 T1101 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> 303498 T1094 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 303504 T1147 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 303504 T1147 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 303505 T1147 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 303819 T1095 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 303821 T1095 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:43631",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "node_name":"127.0.0.1:43631_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1"}
[junit4] 2> 303826 T1095 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:48882",
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "node_name":"127.0.0.1:48882_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 303826 T1095 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 303827 T1095 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 303874 T1101 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> 303874 T1114 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> 303875 T1146 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> 303875 T1130 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> 303874 T1094 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> 304506 T1147 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 304506 T1147 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376224807507/collection1
[junit4] 2> 304507 T1147 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 304507 T1147 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 304508 T1147 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 304509 T1147 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376224807507/collection1/'
[junit4] 2> 304510 T1147 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376224807507/collection1/lib/README' to classloader
[junit4] 2> 304510 T1147 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376224807507/collection1/lib/classes/' to classloader
[junit4] 2> 304538 T1147 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 304572 T1147 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 304573 T1147 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 304580 T1147 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 304903 T1147 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 304904 T1147 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 304905 T1147 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 304910 T1147 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 304913 T1147 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 304932 T1147 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 304936 T1147 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 304940 T1147 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 304942 T1147 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 304942 T1147 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 304943 T1147 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 304944 T1147 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 304944 T1147 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 304944 T1147 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 304945 T1147 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376224807507/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty3/
[junit4] 2> 304945 T1147 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3ac6f479
[junit4] 2> 304946 T1147 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty3
[junit4] 2> 304946 T1147 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty3/index/
[junit4] 2> 304947 T1147 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty3/index' doesn't exist. Creating new index...
[junit4] 2> 304947 T1147 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376224797982/jetty3/index
[junit4] 2> 304948 T1147 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@80d28873 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7b137b4c),segFN=segments_1,generation=1}
[junit4] 2> 304949 T1147 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 304951 T1147 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 304951 T1147 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 304952 T1147 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 304953 T1147 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 304953 T1147 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 304953 T1147 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 304954 T1147 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 304954 T1147 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 304955 T1147 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 304956 T1147 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 304958 T1147 oass.SolrIndexSearcher.<init> Opening Searcher@a707f0e3 main
[junit4] 2> 304959 T1147 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 304959 T1147 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 304962 T1148 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a707f0e3 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 304963 T1147 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 304963 T1147 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:48882 collection:collection1 shard:shard1
[junit4] 2> 304964 T1147 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 304971 T1147 oasc.ZkController.register We are http://127.0.0.1:48882/collection1/ and leader is http://127.0.0.1:55912/collection1/
[junit4] 2> 304972 T1147 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48882
[junit4] 2> 304972 T1147 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 304973 T1147 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C1786 name=collection1 org.apache.solr.core.SolrCore@18b8205 url=http://127.0.0.1:48882/collection1 node=127.0.0.1:48882_ C1786_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:48882, state=down, node_name=127.0.0.1:48882_, core=collection1}
[junit4] 2> 304973 T1149 C1786 P48882 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 304974 T1149 C1786 P48882 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4] 2> 304974 T1147 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 304974 T1149 C1786 P48882 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4] 2> 304975 T1149 C1786 P48882 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 304975 T1072 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 304976 T1072 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 304976 T1149 C1786 P48882 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 304977 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 304982 T1108 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true
[junit4] 2> 305060 T1072 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 305062 T1072 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:४९८६७
[junit4] 2> 305063 T1072 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 305064 T1072 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 305064 T1072 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1376224810149
[junit4] 2> 305065 T1072 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1376224810149/'
[junit4] 2> 305073 T1072 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1376224810149/solr.xml
[junit4] 2> 305103 T1072 oasc.CoreContainer.<init> New CoreContainer -603789400
[junit4] 2> 305104 T1072 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1376224810149/]
[junit4] 2> 305105 T1072 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 305106 T1072 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 305106 T1072 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 305107 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 305107 T1072 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 305108 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 305108 T1072 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 305109 T1072 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 305110 T1072 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 305110 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 305115 T1072 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:36724/solr
[junit4] 2> 305115 T1072 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 305116 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 305118 T1161 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8df19744 name:ZooKeeperConnection Watcher:127.0.0.1:36724 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 305118 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 305120 T1072 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 305124 T1072 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 305125 T1163 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c804463 name:ZooKeeperConnection Watcher:127.0.0.1:36724/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 305126 T1072 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 305129 T1072 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 305384 T1095 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 305385 T1095 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:48882",
[junit4] 2> "state":"recovering",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "node_name":"127.0.0.1:48882_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1"}
[junit4] 2> 305389 T1094 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> 305389 T1101 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> 305390 T1146 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> 305390 T1163 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> 305390 T1130 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> 305390 T1114 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> 305983 T1108 oasha.CoreAdminHandler.handleWaitForStateAction Waited coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 305984 T1108 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={state=recovering&onlyIfLeader=true&checkLive=true&action=PREPRECOVERY&version=2&coreNodeName=core_node3&wt=javabin&nodeName=127.0.0.1:48882_&core=collection1} status=0 QTime=1002
[junit4] 2> 306132 T1072 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49867_
[junit4] 2> 306134 T1072 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49867_
[junit4] 2> 306136 T1094 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> 306136 T1146 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> 306137 T1114 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 306137 T1130 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 306138 T1130 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[...truncated too long message...]
"node_name":"127.0.0.1:55912_",
[junit4] 1> "core":"collection1"}
[junit4] 1> /solr/collections/collection1/leaders/shard2 (0)
[junit4] 1> DATA:
[junit4] 1> {
[junit4] 1> "base_url":"http://127.0.0.1:43631",
[junit4] 1> "node_name":"127.0.0.1:43631_",
[junit4] 1> "core":"collection1"}
[junit4] 1> /solr/collections/collection1/shards (0)
[junit4] 1> /solr/collections/onenodecollection (2)
[junit4] 1> DATA:
[junit4] 1> {
[junit4] 1> "router":"compositeId",
[junit4] 1> "configName":"conf1"}
[junit4] 1> /solr/collections/onenodecollection/leader_elect (1)
[junit4] 1> /solr/collections/onenodecollection/leader_elect/shard1 (1)
[junit4] 1> /solr/collections/onenodecollection/leader_elect/shard1/election (1)
[junit4] 1> /solr/collections/onenodecollection/leader_elect/shard1/election/90192268360810502-core_node1-n_0000000000 (0)
[junit4] 1> /solr/collections/onenodecollection/leaders (1)
[junit4] 1> /solr/collections/onenodecollection/leaders/shard1 (0)
[junit4] 1> DATA:
[junit4] 1> {
[junit4] 1> "base_url":"http://127.0.0.1:55912",
[junit4] 1> "node_name":"127.0.0.1:55912_",
[junit4] 1> "core":"onenodecollectioncore"}
[junit4] 1> /solr/aliases.json (0)
[junit4] 1> /solr/live_nodes (5)
[junit4] 1> /solr/live_nodes/127.0.0.1:49867_ (0)
[junit4] 1> /solr/live_nodes/127.0.0.1:55912_ (0)
[junit4] 1> /solr/live_nodes/127.0.0.1:32810_ (0)
[junit4] 1> /solr/live_nodes/127.0.0.1:48882_ (0)
[junit4] 1> /solr/live_nodes/127.0.0.1:43631_ (0)
[junit4] 1> /solr/clusterstate.json (0)
[junit4] 1> DATA:
[junit4] 1> {
[junit4] 1> "control_collection":{
[junit4] 1> "shards":{"shard1":{
[junit4] 1> "range":"80000000-7fffffff",
[junit4] 1> "state":"active",
[junit4] 1> "replicas":{"core_node1":{
[junit4] 1> "base_url":"http://127.0.0.1:32810",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:32810_",
[junit4] 1> "core":"collection1",
[junit4] 1> "leader":"true"}}}},
[junit4] 1> "maxShardsPerNode":"1",
[junit4] 1> "router":"compositeId",
[junit4] 1> "replicationFactor":"1"},
[junit4] 1> "onenodecollection":{
[junit4] 1> "shards":{"shard1":{
[junit4] 1> "range":"80000000-7fffffff",
[junit4] 1> "state":"active",
[junit4] 1> "replicas":{"core_node1":{
[junit4] 1> "base_url":"http://127.0.0.1:55912",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:55912_",
[junit4] 1> "roles":"none",
[junit4] 1> "core":"onenodecollectioncore",
[junit4] 1> "leader":"true"}}}},
[junit4] 1> "maxShardsPerNode":"1",
[junit4] 1> "router":"compositeId",
[junit4] 1> "replicationFactor":"1"},
[junit4] 1> "collection1":{
[junit4] 1> "shards":{
[junit4] 1> "shard1":{
[junit4] 1> "range":"80000000-ffffffff",
[junit4] 1> "state":"active",
[junit4] 1> "replicas":{
[junit4] 1> "core_node1":{
[junit4] 1> "base_url":"http://127.0.0.1:55912",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:55912_",
[junit4] 1> "core":"collection1",
[junit4] 1> "leader":"true"},
[junit4] 1> "core_node3":{
[junit4] 1> "base_url":"http://127.0.0.1:48882",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:48882_",
[junit4] 1> "core":"collection1"}}},
[junit4] 1> "shard2":{
[junit4] 1> "range":"0-7fffffff",
[junit4] 1> "state":"active",
[junit4] 1> "replicas":{
[junit4] 1> "core_node2":{
[junit4] 1> "base_url":"http://127.0.0.1:43631",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:43631_",
[junit4] 1> "core":"collection1",
[junit4] 1> "leader":"true"},
[junit4] 1> "core_node4":{
[junit4] 1> "base_url":"http://127.0.0.1:49867",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:49867_",
[junit4] 1> "core":"collection1"}}}},
[junit4] 1> "maxShardsPerNode":"1",
[junit4] 1> "router":"compositeId",
[junit4] 1> "replicationFactor":"1"}}
[junit4] 1> /zookeeper (1)
[junit4] 1> DATA:
[junit4] 1>
[junit4] 1>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicDistributedZk2Test -Dtests.method=testDistribSearch -Dtests.seed=E2D13000F788C2CA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=hi_IN -Dtests.timezone=Asia/Dubai -Dtests.file.encoding=UTF-8
[junit4] ERROR 28.0s J1 | BasicDistributedZk2Test.testDistribSearch <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Server at http://127.0.0.1:43631/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
[junit4] > at __randomizedtesting.SeedInfo.seed([E2D13000F788C2CA:6337BE1880D7A2F6]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:385)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4] > at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:196)
[junit4] > at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:88)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
[junit4] > at java.lang.Thread.run(Thread.java:780)
[junit4] 2> 320834 T1072 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=DefaultSimilarity, locale=hi_IN, timezone=Asia/Dubai
[junit4] 2> NOTE: Linux 3.2.0-51-generic x86/IBM Corporation 1.7.0 (32-bit)/cpus=8,threads=3,free=123271864,total=182124544
[junit4] 2> NOTE: All tests run in this JVM: [TestDistributedGrouping, TestSolrXml, HdfsBasicDistributedZk2Test, TestInfoStreamLogging, OutputWriterTest, TestReload, DistanceFunctionTest, SolrPluginUtilsTest, TestSolr4Spatial, TestBinaryResponseWriter, CurrencyFieldXmlFileTest, ZkSolrClientTest, IndexSchemaRuntimeFieldTest, TestNumberUtils, HdfsChaosMonkeySafeLeaderTest, TestDistributedSearch, StressHdfsTest, TestArbitraryIndexDir, SolrRequestParserTest, DebugComponentTest, SimplePostToolTest, IndexBasedSpellCheckerTest, SolrCmdDistributorTest, TestOmitPositions, TestCSVResponseWriter, AssignTest, TestHashPartitioner, TestAddFieldRealTimeGet, TestCloudManagedSchema, SliceStateTest, SortByFunctionTest, TestAtomicUpdateErrorCases, XmlUpdateRequestHandlerTest, SpellCheckCollatorTest, StandardRequestHandlerTest, SpellCheckComponentTest, TestRangeQuery, TestFastOutputStream, DirectUpdateHandlerTest, OpenCloseCoreStressTest, TestRandomMergePolicy, UniqFieldsUpdateProcessorFactoryTest, NoCacheHeaderTest, TestUpdate, TestDefaultSimilarityFactory, RAMDirectoryFactoryTest, TestCSVLoader, TestQuerySenderNoQuery, TestPartialUpdateDeduplication, TestFieldTypeResource, TestUtils, MultiTermTest, TestIBSimilarityFactory, TestSweetSpotSimilarityFactory, JSONWriterTest, BasicDistributedZk2Test]
[junit4] Completed on J1 in 28.05s, 1 test, 1 error <<< FAILURES!
[...truncated 596 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:395: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:388: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1233: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:876: There were test failures: 319 suites, 1350 tests, 1 error, 35 ignored (6 assumptions)
Total time: 45 minutes 10 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.x-Linux (32bit/ibm-j9-jdk7) - Build # 6887
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/6887/
Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
1 tests failed.
FAILED: org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch
Error Message:
Server at http://127.0.0.1:46867/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Server at http://127.0.0.1:46867/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
at __randomizedtesting.SeedInfo.seed([19A8C528640AED6A:984E4B3013558D56]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:385)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:196)
at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:88)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
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:780)
Build Log:
[...truncated 9882 lines...]
[junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4] 2> 717114 T2203 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 717119 T2203 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1376230987946
[junit4] 2> 717120 T2203 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 717121 T2204 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 717221 T2203 oasc.ZkTestServer.run start zk server on port:33076
[junit4] 2> 717226 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 717522 T2210 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e0a720f5 name:ZooKeeperConnection Watcher:127.0.0.1:33076 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 717522 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 717523 T2203 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 717534 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 717536 T2212 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c8010b65 name:ZooKeeperConnection Watcher:127.0.0.1:33076/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 717537 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 717538 T2203 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 717543 T2203 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 717548 T2203 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 717552 T2203 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 717556 T2203 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 717557 T2203 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 717563 T2203 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 717564 T2203 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 717570 T2203 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 717571 T2203 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 717575 T2203 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 717576 T2203 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 717581 T2203 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 717582 T2203 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 717586 T2203 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 717587 T2203 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 717592 T2203 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 717593 T2203 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 717598 T2203 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 717599 T2203 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 717603 T2203 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 717604 T2203 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 717609 T2203 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/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> 717610 T2203 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 717700 T2203 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 717706 T2203 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:36702
[junit4] 2> 717707 T2203 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 717707 T2203 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 717708 T2203 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376230988441
[junit4] 2> 717709 T2203 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376230988441/'
[junit4] 2> 717716 T2203 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376230988441/solr.xml
[junit4] 2> 717748 T2203 oasc.CoreContainer.<init> New CoreContainer 449518456
[junit4] 2> 717748 T2203 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376230988441/]
[junit4] 2> 717750 T2203 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 717750 T2203 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 717751 T2203 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 717752 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 717752 T2203 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 717753 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 717753 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 717754 T2203 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 717754 T2203 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 717755 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 717760 T2203 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:33076/solr
[junit4] 2> 717761 T2203 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 717763 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 717764 T2223 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d1c32eb name:ZooKeeperConnection Watcher:127.0.0.1:33076 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 717765 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 717766 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 717772 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 717773 T2225 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@efcf4f21 name:ZooKeeperConnection Watcher:127.0.0.1:33076/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 717774 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 717776 T2203 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 717779 T2203 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 717781 T2203 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 717783 T2203 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36702_
[junit4] 2> 717784 T2203 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:36702_
[junit4] 2> 717786 T2203 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 717791 T2203 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 717793 T2203 oasc.Overseer.start Overseer (id=90192674026422275-127.0.0.1:36702_-n_0000000000) starting
[junit4] 2> 717797 T2203 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 717800 T2227 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 717801 T2203 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 717804 T2203 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 717806 T2203 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 717810 T2226 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 717816 T2228 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 717816 T2228 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 717818 T2228 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 719315 T2226 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 719317 T2226 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:36702",
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:36702_",
[junit4] 2> "numShards":"1",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 719317 T2226 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 719318 T2226 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 719327 T2225 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> 719819 T2228 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 719820 T2228 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376230988441/collection1
[junit4] 2> 719820 T2228 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 719822 T2228 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 719822 T2228 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 719824 T2228 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376230988441/collection1/'
[junit4] 2> 719826 T2228 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376230988441/collection1/lib/README' to classloader
[junit4] 2> 719827 T2228 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376230988441/collection1/lib/classes/' to classloader
[junit4] 2> 719864 T2228 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 719897 T2228 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 719898 T2228 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 719905 T2228 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 720252 T2228 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 720253 T2228 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 720254 T2228 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 720259 T2228 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 720261 T2228 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 720278 T2228 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 720282 T2228 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 720287 T2228 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 720289 T2228 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 720289 T2228 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 720290 T2228 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 720292 T2228 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 720293 T2228 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 720294 T2228 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 720295 T2228 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1376230988441/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/control/data/
[junit4] 2> 720295 T2228 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8e8407ab
[junit4] 2> 720297 T2228 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/control/data
[junit4] 2> 720298 T2228 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/control/data/index/
[junit4] 2> 720298 T2228 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 720300 T2228 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/control/data/index
[junit4] 2> 720304 T2228 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/index1908319084tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@706e3a2e)),segFN=segments_1,generation=1}
[junit4] 2> 720305 T2228 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 720309 T2228 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 720310 T2228 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 720311 T2228 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 720312 T2228 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 720313 T2228 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 720314 T2228 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 720314 T2228 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 720315 T2228 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 720316 T2228 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 720319 T2228 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 720323 T2228 oass.SolrIndexSearcher.<init> Opening Searcher@3c472eb2 main
[junit4] 2> 720325 T2228 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 720326 T2228 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 720328 T2229 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c472eb2 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 720329 T2228 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 720329 T2228 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:36702 collection:control_collection shard:shard1
[junit4] 2> 720329 T2228 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 720341 T2228 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 720346 T2228 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 720348 T2228 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 720348 T2228 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 720349 T2228 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:36702/collection1/
[junit4] 2> 720349 T2228 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 720349 T2228 oasc.SyncStrategy.syncToMe http://127.0.0.1:36702/collection1/ has no replicas
[junit4] 2> 720350 T2228 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:36702/collection1/ shard1
[junit4] 2> 720350 T2228 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 720832 T2226 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 720841 T2225 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> 720855 T2228 oasc.ZkController.register We are http://127.0.0.1:36702/collection1/ and leader is http://127.0.0.1:36702/collection1/
[junit4] 2> 720856 T2228 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:36702
[junit4] 2> 720856 T2228 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 720857 T2228 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 720857 T2228 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 720859 T2228 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 720861 T2203 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 720862 T2203 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 720864 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 720870 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4] 2> 720873 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 720875 T2232 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4d435609 name:ZooKeeperConnection Watcher:127.0.0.1:33076/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 720875 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 720877 T2203 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 720880 T2203 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4] 2> 720951 T2203 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 720956 T2203 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59263
[junit4] 2> 720956 T2203 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 720957 T2203 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 720958 T2203 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376230991706
[junit4] 2> 720958 T2203 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376230991706/'
[junit4] 2> 720966 T2203 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376230991706/solr.xml
[junit4] 2> 720997 T2203 oasc.CoreContainer.<init> New CoreContainer -247528969
[junit4] 2> 720998 T2203 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376230991706/]
[junit4] 2> 720999 T2203 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 721000 T2203 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 721000 T2203 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 721001 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 721002 T2203 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 721002 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 721003 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 721003 T2203 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 721004 T2203 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 721004 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 721010 T2203 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:33076/solr
[junit4] 2> 721010 T2203 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 721013 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 721014 T2243 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c283687 name:ZooKeeperConnection Watcher:127.0.0.1:33076 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 721014 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 721015 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 721022 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 721022 T2245 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@521a74e name:ZooKeeperConnection Watcher:127.0.0.1:33076/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 721023 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 721026 T2203 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 722029 T2203 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59263_
[junit4] 2> 722031 T2203 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59263_
[junit4] 2> 722036 T2225 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> 722036 T2232 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 722037 T2245 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 722039 T2225 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 722046 T2246 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 722047 T2246 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 722048 T2246 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 722350 T2226 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 722352 T2226 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:36702",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:36702_",
[junit4] 2> "numShards":"1",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1"}
[junit4] 2> 722357 T2226 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:59263",
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:59263_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 722358 T2226 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 722359 T2226 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 722364 T2245 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> 722364 T2225 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> 722364 T2232 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> 723048 T2246 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 723049 T2246 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376230991706/collection1
[junit4] 2> 723050 T2246 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 723051 T2246 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 723052 T2246 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 723054 T2246 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376230991706/collection1/'
[junit4] 2> 723055 T2246 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376230991706/collection1/lib/README' to classloader
[junit4] 2> 723056 T2246 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376230991706/collection1/lib/classes/' to classloader
[junit4] 2> 723101 T2246 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 723133 T2246 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 723134 T2246 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 723140 T2246 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 723465 T2246 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 723466 T2246 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 723466 T2246 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 723471 T2246 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 723474 T2246 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 723484 T2246 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 723488 T2246 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 723493 T2246 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 723495 T2246 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 723495 T2246 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 723496 T2246 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 723497 T2246 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 723497 T2246 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 723498 T2246 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 723498 T2246 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1376230991706/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty1/
[junit4] 2> 723499 T2246 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8e8407ab
[junit4] 2> 723500 T2246 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty1
[junit4] 2> 723500 T2246 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty1/index/
[junit4] 2> 723501 T2246 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 723502 T2246 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty1/index
[junit4] 2> 723504 T2246 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/index1908319085tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@fb55a1d3)),segFN=segments_1,generation=1}
[junit4] 2> 723504 T2246 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 723508 T2246 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 723508 T2246 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 723509 T2246 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 723509 T2246 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 723510 T2246 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 723510 T2246 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 723511 T2246 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 723511 T2246 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 723512 T2246 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 723513 T2246 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 723516 T2246 oass.SolrIndexSearcher.<init> Opening Searcher@16b8d06d main
[junit4] 2> 723516 T2246 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 723517 T2246 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 723521 T2247 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16b8d06d main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 723522 T2246 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 723523 T2246 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:59263 collection:collection1 shard:shard1
[junit4] 2> 723523 T2246 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 723528 T2246 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 723532 T2246 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 723534 T2246 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 723534 T2246 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 723535 T2246 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:59263/collection1/
[junit4] 2> 723535 T2246 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 723535 T2246 oasc.SyncStrategy.syncToMe http://127.0.0.1:59263/collection1/ has no replicas
[junit4] 2> 723536 T2246 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:59263/collection1/ shard1
[junit4] 2> 723536 T2246 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 723871 T2226 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 723884 T2245 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> 723884 T2232 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> 723884 T2225 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> 723892 T2246 oasc.ZkController.register We are http://127.0.0.1:59263/collection1/ and leader is http://127.0.0.1:59263/collection1/
[junit4] 2> 723892 T2246 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:59263
[junit4] 2> 723893 T2246 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 723894 T2246 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 723894 T2246 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 723897 T2246 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 723899 T2203 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 723900 T2203 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 723901 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 723976 T2203 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 723980 T2203 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46867
[junit4] 2> 723981 T2203 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 723981 T2203 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 723982 T2203 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376230994735
[junit4] 2> 723982 T2203 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376230994735/'
[junit4] 2> 723997 T2203 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376230994735/solr.xml
[junit4] 2> 724047 T2203 oasc.CoreContainer.<init> New CoreContainer -1322676603
[junit4] 2> 724047 T2203 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376230994735/]
[junit4] 2> 724049 T2203 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 724049 T2203 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 724050 T2203 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 724051 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 724051 T2203 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 724052 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 724052 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 724053 T2203 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 724054 T2203 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 724054 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 724059 T2203 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:33076/solr
[junit4] 2> 724059 T2203 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 724061 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 724063 T2259 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3c31ee64 name:ZooKeeperConnection Watcher:127.0.0.1:33076 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 724063 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 724065 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 724071 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 724072 T2261 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7bb854e7 name:ZooKeeperConnection Watcher:127.0.0.1:33076/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 724072 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 724075 T2203 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 725078 T2203 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46867_
[junit4] 2> 725080 T2203 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46867_
[junit4] 2> 725084 T2225 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> 725085 T2245 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 725086 T2245 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> 725086 T2232 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 725087 T2261 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 725088 T2225 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 725087 T2232 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> 725095 T2262 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 725096 T2262 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 725098 T2262 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 725392 T2226 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 725394 T2226 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:59263",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"127.0.0.1:59263_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1"}
[junit4] 2> 725401 T2226 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:46867",
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "node_name":"127.0.0.1:46867_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 725402 T2226 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 725402 T2226 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 725408 T2261 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> 725408 T2245 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> 725408 T2225 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> 725408 T2232 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> 726099 T2262 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 726100 T2262 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376230994735/collection1
[junit4] 2> 726101 T2262 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 726102 T2262 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 726103 T2262 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 726105 T2262 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376230994735/collection1/'
[junit4] 2> 726106 T2262 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376230994735/collection1/lib/README' to classloader
[junit4] 2> 726107 T2262 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376230994735/collection1/lib/classes/' to classloader
[junit4] 2> 726143 T2262 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 726175 T2262 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 726177 T2262 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 726183 T2262 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 726504 T2262 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 726504 T2262 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 726505 T2262 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 726509 T2262 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 726512 T2262 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 726524 T2262 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 726528 T2262 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 726534 T2262 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 726535 T2262 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 726536 T2262 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 726537 T2262 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 726538 T2262 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 726539 T2262 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 726539 T2262 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 726540 T2262 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1376230994735/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty2/
[junit4] 2> 726540 T2262 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8e8407ab
[junit4] 2> 726541 T2262 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty2
[junit4] 2> 726542 T2262 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty2/index/
[junit4] 2> 726542 T2262 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 726544 T2262 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty2/index
[junit4] 2> 726546 T2262 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/index1908319086tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@9e43e09d)),segFN=segments_1,generation=1}
[junit4] 2> 726547 T2262 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 726551 T2262 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 726551 T2262 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 726552 T2262 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 726553 T2262 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 726554 T2262 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 726554 T2262 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 726555 T2262 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 726556 T2262 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 726556 T2262 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 726558 T2262 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 726562 T2262 oass.SolrIndexSearcher.<init> Opening Searcher@147b9efc main
[junit4] 2> 726563 T2262 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 726563 T2262 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 726567 T2263 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@147b9efc main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 726568 T2262 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 726569 T2262 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:46867 collection:collection1 shard:shard2
[junit4] 2> 726569 T2262 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 726573 T2262 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 726580 T2262 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 726583 T2262 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 726584 T2262 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 726584 T2262 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:46867/collection1/
[junit4] 2> 726584 T2262 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 726584 T2262 oasc.SyncStrategy.syncToMe http://127.0.0.1:46867/collection1/ has no replicas
[junit4] 2> 726585 T2262 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:46867/collection1/ shard2
[junit4] 2> 726585 T2262 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 726916 T2226 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 726931 T2261 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> 726932 T2245 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> 726932 T2225 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> 726932 T2232 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> 726943 T2262 oasc.ZkController.register We are http://127.0.0.1:46867/collection1/ and leader is http://127.0.0.1:46867/collection1/
[junit4] 2> 726944 T2262 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:46867
[junit4] 2> 726944 T2262 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 726945 T2262 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 726945 T2262 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 726948 T2262 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 726950 T2203 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 726951 T2203 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 726953 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 727043 T2203 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 727046 T2203 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56489
[junit4] 2> 727047 T2203 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 727048 T2203 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 727048 T2203 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376230997787
[junit4] 2> 727049 T2203 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376230997787/'
[junit4] 2> 727056 T2203 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376230997787/solr.xml
[junit4] 2> 727102 T2203 oasc.CoreContainer.<init> New CoreContainer -449669715
[junit4] 2> 727103 T2203 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376230997787/]
[junit4] 2> 727106 T2203 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 727107 T2203 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 727108 T2203 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 727108 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 727109 T2203 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 727109 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 727110 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 727110 T2203 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 727111 T2203 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 727112 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 727116 T2203 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:33076/solr
[junit4] 2> 727117 T2203 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 727119 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 727120 T2275 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9f269dd3 name:ZooKeeperConnection Watcher:127.0.0.1:33076 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 727120 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 727122 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 727127 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 727128 T2277 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e889c4a name:ZooKeeperConnection Watcher:127.0.0.1:33076/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 727129 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 727131 T2203 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 728135 T2203 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56489_
[junit4] 2> 728137 T2203 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56489_
[junit4] 2> 728141 T2225 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> 728142 T2232 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 728142 T2245 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 728142 T2261 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 728143 T2232 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> 728142 T2277 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 728144 T2261 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> 728144 T2225 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 728144 T2245 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> 728153 T2278 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 728153 T2278 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 728155 T2278 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 728440 T2226 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 728442 T2226 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:46867",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "node_name":"127.0.0.1:46867_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1"}
[junit4] 2> 728448 T2226 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:56489",
[junit4] 2> "state":"down",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "node_name":"127.0.0.1:56489_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":null,
[junit4] 2> "core":"collection1"}
[junit4] 2> 728449 T2226 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 728449 T2226 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 728455 T2277 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> 728455 T2261 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> 728455 T2245 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> 728455 T2232 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> 728455 T2225 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> 729156 T2278 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 729157 T2278 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376230997787/collection1
[junit4] 2> 729158 T2278 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 729159 T2278 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 729160 T2278 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 729162 T2278 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376230997787/collection1/'
[junit4] 2> 729164 T2278 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376230997787/collection1/lib/README' to classloader
[junit4] 2> 729164 T2278 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376230997787/collection1/lib/classes/' to classloader
[junit4] 2> 729192 T2278 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 729226 T2278 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 729228 T2278 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 729234 T2278 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 729575 T2278 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 729576 T2278 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 729576 T2278 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 729581 T2278 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 729584 T2278 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 729599 T2278 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 729605 T2278 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 729611 T2278 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 729614 T2278 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 729614 T2278 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 729615 T2278 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 729617 T2278 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 729618 T2278 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 729618 T2278 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 729619 T2278 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1376230997787/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty3/
[junit4] 2> 729620 T2278 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8e8407ab
[junit4] 2> 729621 T2278 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty3
[junit4] 2> 729622 T2278 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty3/index/
[junit4] 2> 729622 T2278 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty3/index' doesn't exist. Creating new index...
[junit4] 2> 729624 T2278 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1376230987946/jetty3/index
[junit4] 2> 729627 T2278 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/index1908319087tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@2d07daa7)),segFN=segments_1,generation=1}
[junit4] 2> 729628 T2278 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 729632 T2278 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 729633 T2278 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 729634 T2278 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 729635 T2278 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 729636 T2278 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 729636 T2278 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 729637 T2278 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 729638 T2278 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 729638 T2278 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 729641 T2278 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 729645 T2278 oass.SolrIndexSearcher.<init> Opening Searcher@1ff9f361 main
[junit4] 2> 729646 T2278 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 729647 T2278 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 729650 T2279 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ff9f361 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 729651 T2278 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 729652 T2278 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:56489 collection:collection1 shard:shard1
[junit4] 2> 729652 T2278 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 729658 T2278 oasc.ZkController.register We are http://127.0.0.1:56489/collection1/ and leader is http://127.0.0.1:59263/collection1/
[junit4] 2> 729659 T2278 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56489
[junit4] 2> 729659 T2278 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 729659 T2278 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C3808 name=collection1 org.apache.solr.core.SolrCore@25eb00b0 url=http://127.0.0.1:56489/collection1 node=127.0.0.1:56489_ C3808_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:56489, state=down, node_name=127.0.0.1:56489_, core=collection1}
[junit4] 2> 729660 T2280 C3808 P56489 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 729660 T2278 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 729660 T2280 C3808 P56489 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4] 2> 729661 T2280 C3808 P56489 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4] 2> 729661 T2280 C3808 P56489 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 729661 T2203 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 729662 T2203 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 729662 T2280 C3808 P56489 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 729663 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 729669 T2240 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true
[junit4] 2> 729737 T2203 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 729740 T2203 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:48960
[junit4] 2> 729741 T2203 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 729742 T2203 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 729742 T2203 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1376231000495
[junit4] 2> 729743 T2203 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1376231000495/'
[junit4] 2> 729751 T2203 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1376231000495/solr.xml
[junit4] 2> 729782 T2203 oasc.CoreContainer.<init> New CoreContainer 1276199528
[junit4] 2> 729783 T2203 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1376231000495/]
[junit4] 2> 729784 T2203 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 729785 T2203 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 729785 T2203 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 729786 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 729786 T2203 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 729787 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 729788 T2203 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 729788 T2203 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 729789 T2203 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 729789 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 729794 T2203 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:33076/solr
[junit4] 2> 729795 T2203 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 729796 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 729798 T2292 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a9e01ea8 name:ZooKeeperConnection Watcher:127.0.0.1:33076 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 729798 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 729800 T2203 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 729808 T2203 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 729810 T2294 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@50e21d2f name:ZooKeeperConnection Watcher:127.0.0.1:33076/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 729810 T2203 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 729815 T2203 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 729963 T2226 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 729965 T2226 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "shard_state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:56489",
[junit4] 2> "state":"recovering",
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "node_name":"127.0.0.1:56489_",
[junit4] 2> "numShards":"2",
[junit4] 2> "shard_range":null,
[junit4] 2> "roles":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1"}
[junit4] 2> 729971 T2261 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> 729971 T2277 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> 729971 T2232 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> 729971 T2225 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> 729971 T2294 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> 729971 T2245 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> 730670 T2240 oasha.CoreAdminHandler.handleWaitForStateAction Waited coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 730672 T2240 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={state=recovering&onlyIfLeader=true&checkLive=true&action=PREPRECOVERY&version=2&coreNodeName=core_node3&wt=javabin&nodeName=127.0.0.1:56489_&core=collection1} status=0 QTime=1003
[junit4] 2> 730818 T2203 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48960_
[junit4] 2> 730821 T2203 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48960_
[junit4] 2> 730824 T2294 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> 730825 T2261 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4] 2> 730826 T2245 oasc
[...truncated too long message...]
[junit4] 1> /solr/collections/onenodecollection/leader_elect (1)
[junit4] 1> /solr/collections/onenodecollection/leader_elect/shard1 (1)
[junit4] 1> /solr/collections/onenodecollection/leader_elect/shard1/election (1)
[junit4] 1> /solr/collections/onenodecollection/leader_elect/shard1/election/90192674026422278-core_node1-n_0000000000 (0)
[junit4] 1> /solr/collections/onenodecollection/leaders (1)
[junit4] 1> /solr/collections/onenodecollection/leaders/shard1 (0)
[junit4] 1> DATA:
[junit4] 1> {
[junit4] 1> "base_url":"http://127.0.0.1:59263",
[junit4] 1> "node_name":"127.0.0.1:59263_",
[junit4] 1> "core":"onenodecollectioncore"}
[junit4] 1> /solr/aliases.json (0)
[junit4] 1> /solr/live_nodes (5)
[junit4] 1> /solr/live_nodes/127.0.0.1:56489_ (0)
[junit4] 1> /solr/live_nodes/127.0.0.1:59263_ (0)
[junit4] 1> /solr/live_nodes/127.0.0.1:48960_ (0)
[junit4] 1> /solr/live_nodes/127.0.0.1:46867_ (0)
[junit4] 1> /solr/live_nodes/127.0.0.1:36702_ (0)
[junit4] 1> /solr/clusterstate.json (0)
[junit4] 1> DATA:
[junit4] 1> {
[junit4] 1> "control_collection":{
[junit4] 1> "shards":{"shard1":{
[junit4] 1> "range":"80000000-7fffffff",
[junit4] 1> "state":"active",
[junit4] 1> "replicas":{"core_node1":{
[junit4] 1> "base_url":"http://127.0.0.1:36702",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:36702_",
[junit4] 1> "core":"collection1",
[junit4] 1> "leader":"true"}}}},
[junit4] 1> "maxShardsPerNode":"1",
[junit4] 1> "router":"compositeId",
[junit4] 1> "replicationFactor":"1"},
[junit4] 1> "onenodecollection":{
[junit4] 1> "shards":{"shard1":{
[junit4] 1> "range":"80000000-7fffffff",
[junit4] 1> "state":"active",
[junit4] 1> "replicas":{"core_node1":{
[junit4] 1> "base_url":"http://127.0.0.1:59263",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:59263_",
[junit4] 1> "roles":"none",
[junit4] 1> "core":"onenodecollectioncore",
[junit4] 1> "leader":"true"}}}},
[junit4] 1> "maxShardsPerNode":"1",
[junit4] 1> "router":"compositeId",
[junit4] 1> "replicationFactor":"1"},
[junit4] 1> "collection1":{
[junit4] 1> "shards":{
[junit4] 1> "shard1":{
[junit4] 1> "range":"80000000-ffffffff",
[junit4] 1> "state":"active",
[junit4] 1> "replicas":{
[junit4] 1> "core_node1":{
[junit4] 1> "base_url":"http://127.0.0.1:59263",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:59263_",
[junit4] 1> "core":"collection1",
[junit4] 1> "leader":"true"},
[junit4] 1> "core_node3":{
[junit4] 1> "base_url":"http://127.0.0.1:56489",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:56489_",
[junit4] 1> "core":"collection1"}}},
[junit4] 1> "shard2":{
[junit4] 1> "range":"0-7fffffff",
[junit4] 1> "state":"active",
[junit4] 1> "replicas":{
[junit4] 1> "core_node2":{
[junit4] 1> "base_url":"http://127.0.0.1:46867",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:46867_",
[junit4] 1> "core":"collection1",
[junit4] 1> "leader":"true"},
[junit4] 1> "core_node4":{
[junit4] 1> "base_url":"http://127.0.0.1:48960",
[junit4] 1> "state":"active",
[junit4] 1> "node_name":"127.0.0.1:48960_",
[junit4] 1> "core":"collection1"}}}},
[junit4] 1> "maxShardsPerNode":"1",
[junit4] 1> "router":"compositeId",
[junit4] 1> "replicationFactor":"1"}}
[junit4] 1> /zookeeper (1)
[junit4] 1> DATA:
[junit4] 1>
[junit4] 1>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicDistributedZk2Test -Dtests.method=testDistribSearch -Dtests.seed=19A8C528640AED6A -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr_CA -Dtests.timezone=Australia/Melbourne -Dtests.file.encoding=UTF-8
[junit4] ERROR 28.6s J1 | BasicDistributedZk2Test.testDistribSearch <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Server at http://127.0.0.1:46867/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
[junit4] > at __randomizedtesting.SeedInfo.seed([19A8C528640AED6A:984E4B3013558D56]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:385)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4] > at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:196)
[junit4] > at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:88)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
[junit4] > at java.lang.Thread.run(Thread.java:780)
[junit4] 2> 745743 T2203 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 28636 T2202 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene41, sim=DefaultSimilarity, locale=fr_CA, timezone=Australia/Melbourne
[junit4] 2> NOTE: Linux 3.2.0-51-generic x86/IBM Corporation 1.7.0 (32-bit)/cpus=8,threads=1,free=42768576,total=246087680
[junit4] 2> NOTE: All tests run in this JVM: [TestRecoveryHdfs, PreAnalyzedFieldTest, ChaosMonkeySafeLeaderTest, OpenCloseCoreStressTest, TestRandomMergePolicy, TestPhraseSuggestions, SpellCheckCollatorTest, SignatureUpdateProcessorFactoryTest, ZkSolrClientTest, TestLazyCores, TestNumberUtils, TestWordDelimiterFilterFactory, TestUpdate, TestFastWriter, ConvertedLegacyTest, TestSerializedLuceneMatchVersion, TestReloadAndDeleteDocs, DirectSolrConnectionTest, ClusterStateTest, TestDefaultSimilarityFactory, UUIDFieldTest, SpellCheckComponentTest, DistanceFunctionTest, NumericFieldsTest, TestAddFieldRealTimeGet, DocumentAnalysisRequestHandlerTest, FieldAnalysisRequestHandlerTest, PluginInfoTest, MinimalSchemaTest, OutputWriterTest, TestManagedSchemaFieldResource, TestQuerySenderListener, PathHierarchyTokenizerFactoryTest, IndexSchemaRuntimeFieldTest, SuggesterWFSTTest, StressHdfsTest, TestXIncludeConfig, CircularListTest, SimplePostToolTest, HighlighterTest, TestJmxIntegration, TestFuzzyAnalyzedSuggestions, TestQuerySenderNoQuery, HardAutoCommitTest, TestBinaryResponseWriter, TestRangeQuery, HdfsRecoveryZkTest, RequiredFieldsTest, CustomCollectionTest, JSONWriterTest, SolrIndexConfigTest, DateMathParserTest, TestIBSimilarityFactory, PingRequestHandlerTest, PrimUtilsTest, NoCacheHeaderTest, SuggesterTSTTest, StandardRequestHandlerTest, TestCoreDiscovery, TestIndexSearcher, TestDFRSimilarityFactory, TestStressLucene, CoreMergeIndexesAdminHandlerTest, BadIndexSchemaTest, TestAnalyzedSuggestions, TestSolrXml, DefaultValueUpdateProcessorTest, CachingDirectoryFactoryTest, XsltUpdateRequestHandlerTest, HdfsSyncSliceTest, TestFieldCollectionResource, SortByFunctionTest, TestAtomicUpdateErrorCases, SolrCoreCheckLockOnStartupTest, TestRecovery, StatsComponentTest, BasicDistributedZkTest, BasicDistributedZk2Test]
[junit4] Completed on J1 in 29.19s, 1 test, 1 error <<< FAILURES!
[...truncated 505 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:395: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:388: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1233: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:876: There were test failures: 319 suites, 1350 tests, 1 error, 35 ignored (6 assumptions)
Total time: 47 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure