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/10/19 17:17:49 UTC
[JENKINS] Lucene-Solr-4.x-Linux (32bit/ibm-j9-jdk7) - Build # 7856
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/7856/
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:
Expected content type application/octet-stream but got text/html;charset=ISO-8859-1. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 404 Can not find: /onenodecollectioncore/update</title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing /onenodecollectioncore/update. Reason: <pre> Can not find: /onenodecollectioncore/update</pre></p> <hr /><i><small>Powered by Jetty://</small></i> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Expected content type application/octet-stream but got text/html;charset=ISO-8859-1. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 404 Can not find: /onenodecollectioncore/update</title>
</head>
<body>
<h2>HTTP ERROR: 404</h2>
<p>Problem accessing /onenodecollectioncore/update. Reason:
<pre> Can not find: /onenodecollectioncore/update</pre></p>
<hr /><i><small>Powered by Jetty://</small></i>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([7A991F138C19AA7A:FB7F910BFB46CA46]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:428)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
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 10065 lines...]
[junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4] 2> 331400 T980 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 331404 T980 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-1382194852703
[junit4] 2> 331405 T980 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 331406 T981 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 331506 T980 oasc.ZkTestServer.run start zk server on port:37417
[junit4] 2> 331507 T980 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 331609 T987 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2c5ec545 name:ZooKeeperConnection Watcher:127.0.0.1:37417 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 331609 T980 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 331610 T980 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 331614 T980 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 331615 T989 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f8b3e051 name:ZooKeeperConnection Watcher:127.0.0.1:37417/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 331616 T980 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 331616 T980 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 331619 T980 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 331622 T980 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 331623 T980 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 331626 T980 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> 331626 T980 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 331631 T980 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> 331631 T980 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 331634 T980 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> 331635 T980 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 331638 T980 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> 331639 T980 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 331643 T980 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> 331644 T980 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 331647 T980 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> 331648 T980 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 331650 T980 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> 331651 T980 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 331655 T980 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> 331656 T980 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 331659 T980 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> 331659 T980 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 331663 T980 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> 331663 T980 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 331759 T980 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 331762 T980 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:37631
[junit4] 2> 331763 T980 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 331764 T980 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 331765 T980 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-1382194852965
[junit4] 2> 331766 T980 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-1382194852965/'
[junit4] 2> 331779 T980 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1382194852965/solr.xml
[junit4] 2> 331830 T980 oasc.CoreContainer.<init> New CoreContainer 576123890
[junit4] 2> 331831 T980 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-1382194852965/]
[junit4] 2> 331833 T980 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 331834 T980 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 331834 T980 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 331835 T980 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 331835 T980 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 331836 T980 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 331837 T980 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 331837 T980 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 331838 T980 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 331839 T980 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 331840 T980 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 331841 T980 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:37417/solr
[junit4] 2> 331841 T980 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 331842 T980 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 331844 T1000 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a62e6530 name:ZooKeeperConnection Watcher:127.0.0.1:37417 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 331844 T980 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 331846 T980 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 331848 T1002 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bd211473 name:ZooKeeperConnection Watcher:127.0.0.1:37417/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 331848 T980 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 331850 T980 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 331852 T980 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 331855 T980 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 331857 T980 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37631_
[junit4] 2> 331858 T980 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37631_
[junit4] 2> 331861 T980 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 331864 T980 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 331866 T980 oasc.Overseer.start Overseer (id=90583521866874883-127.0.0.1:37631_-n_0000000000) starting
[junit4] 2> 331869 T980 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 331873 T980 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 331873 T1004 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 331875 T980 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 331877 T980 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 331879 T1003 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 331881 T1005 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 331882 T1005 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 331883 T1005 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 333382 T1003 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 333383 T1003 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37631",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37631_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 333384 T1003 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 333384 T1003 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 333389 T1002 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> 333883 T1005 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 333884 T1005 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-1382194852965/collection1
[junit4] 2> 333884 T1005 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 333885 T1005 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 333886 T1005 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 333887 T1005 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-1382194852965/collection1/'
[junit4] 2> 333888 T1005 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-1382194852965/collection1/lib/classes/' to classloader
[junit4] 2> 333889 T1005 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-1382194852965/collection1/lib/README' to classloader
[junit4] 2> 333925 T1005 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 333957 T1005 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 333958 T1005 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 333964 T1005 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 334298 T1005 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 334299 T1005 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 334299 T1005 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 334304 T1005 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 334307 T1005 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 334319 T1005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 334328 T1005 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 334332 T1005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 334333 T1005 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 334334 T1005 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 334334 T1005 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 334335 T1005 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 334335 T1005 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 334336 T1005 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 334336 T1005 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-1382194852965/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/control/data/
[junit4] 2> 334336 T1005 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7ec6e7e7
[junit4] 2> 334337 T1005 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/control/data
[junit4] 2> 334338 T1005 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/control/data/index/
[junit4] 2> 334338 T1005 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 334338 T1005 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/control/data/index
[junit4] 2> 334339 T1005 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31137590061266096]
[junit4] 2> 334339 T1005 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@ead9268b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c9d34282),segFN=segments_1,generation=1}
[junit4] 2> 334340 T1005 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 334342 T1005 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 334342 T1005 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 334342 T1005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 334343 T1005 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 334343 T1005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 334343 T1005 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 334344 T1005 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 334344 T1005 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 334345 T1005 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 334345 T1005 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 334346 T1005 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 334346 T1005 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 334346 T1005 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 334347 T1005 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 334347 T1005 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 334348 T1005 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 334351 T1005 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 334351 T1005 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 334352 T1005 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=201473244, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 334353 T1005 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@ead9268b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c9d34282),segFN=segments_1,generation=1}
[junit4] 2> 334353 T1005 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 334354 T1005 oass.SolrIndexSearcher.<init> Opening Searcher@5f634310 main
[junit4] 2> 334356 T1006 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5f634310 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 334356 T1005 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 334357 T1005 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:37631 collection:control_collection shard:shard1
[junit4] 2> 334358 T1005 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 334364 T1005 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 334366 T1005 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 334366 T1005 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 334367 T1005 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:37631/collection1/
[junit4] 2> 334367 T1005 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 334367 T1005 oasc.SyncStrategy.syncToMe http://127.0.0.1:37631/collection1/ has no replicas
[junit4] 2> 334368 T1005 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:37631/collection1/ shard1
[junit4] 2> 334368 T1005 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 334892 T1003 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 334897 T1002 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> 334924 T1005 oasc.ZkController.register We are http://127.0.0.1:37631/collection1/ and leader is http://127.0.0.1:37631/collection1/
[junit4] 2> 334924 T1005 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37631
[junit4] 2> 334925 T1005 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 334925 T1005 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 334926 T1005 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 334928 T1005 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 334929 T980 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 334930 T980 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 334932 T980 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 334933 T1009 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d002a2b name:ZooKeeperConnection Watcher:127.0.0.1:37417/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 334934 T980 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 334935 T980 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 334937 T980 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4] 2> 335005 T980 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 335007 T980 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:34148
[junit4] 2> 335008 T980 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 335009 T980 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 335009 T980 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1382194856236
[junit4] 2> 335010 T980 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1382194856236/'
[junit4] 2> 335018 T980 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-1382194856236/solr.xml
[junit4] 2> 335051 T980 oasc.CoreContainer.<init> New CoreContainer -1218735385
[junit4] 2> 335051 T980 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1382194856236/]
[junit4] 2> 335053 T980 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 335053 T980 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 335054 T980 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 335055 T980 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 335055 T980 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 335056 T980 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 335057 T980 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 335057 T980 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 335058 T980 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 335059 T980 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 335060 T980 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 335061 T980 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:37417/solr
[junit4] 2> 335061 T980 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 335062 T980 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 335064 T1020 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@609993eb name:ZooKeeperConnection Watcher:127.0.0.1:37417 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 335065 T980 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 335067 T980 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 335068 T1022 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@35eb5be9 name:ZooKeeperConnection Watcher:127.0.0.1:37417/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 335069 T980 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 335072 T980 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 336074 T980 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34148_
[junit4] 2> 336076 T980 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:34148_
[junit4] 2> 336078 T1009 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 336078 T1002 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 336079 T1022 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 336084 T1023 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 336084 T1023 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 336085 T1023 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 336401 T1003 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 336402 T1003 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:37631",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37631_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 336404 T1003 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:34148",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:34148_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 336405 T1003 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 336405 T1003 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 336409 T1009 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> 336409 T1002 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> 336409 T1022 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> 337085 T1023 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 337086 T1023 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1382194856236/collection1
[junit4] 2> 337086 T1023 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 337087 T1023 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 337087 T1023 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 337088 T1023 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1382194856236/collection1/'
[junit4] 2> 337089 T1023 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-1382194856236/collection1/lib/classes/' to classloader
[junit4] 2> 337089 T1023 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-1382194856236/collection1/lib/README' to classloader
[junit4] 2> 337125 T1023 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 337156 T1023 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 337158 T1023 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 337164 T1023 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 337504 T1023 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 337505 T1023 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 337505 T1023 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 337510 T1023 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 337513 T1023 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 337525 T1023 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 337529 T1023 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 337533 T1023 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 337535 T1023 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 337535 T1023 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 337535 T1023 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 337536 T1023 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 337537 T1023 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 337537 T1023 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 337538 T1023 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1382194856236/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty1/
[junit4] 2> 337538 T1023 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7ec6e7e7
[junit4] 2> 337539 T1023 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty1
[junit4] 2> 337539 T1023 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty1/index/
[junit4] 2> 337539 T1023 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 337540 T1023 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty1/index
[junit4] 2> 337540 T1023 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31137590061266096]
[junit4] 2> 337541 T1023 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@52b95b55 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cede3268),segFN=segments_1,generation=1}
[junit4] 2> 337541 T1023 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 337544 T1023 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 337544 T1023 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 337544 T1023 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 337545 T1023 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 337545 T1023 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 337545 T1023 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 337546 T1023 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 337546 T1023 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 337547 T1023 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 337547 T1023 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 337548 T1023 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 337548 T1023 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 337548 T1023 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 337549 T1023 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 337549 T1023 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 337551 T1023 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 337553 T1023 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 337554 T1023 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 337554 T1023 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=201473244, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 337555 T1023 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@52b95b55 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cede3268),segFN=segments_1,generation=1}
[junit4] 2> 337555 T1023 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 337556 T1023 oass.SolrIndexSearcher.<init> Opening Searcher@2bd76c0b main
[junit4] 2> 337559 T1024 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2bd76c0b main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 337560 T1023 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 337560 T1023 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:34148 collection:collection1 shard:shard1
[junit4] 2> 337562 T1023 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 337569 T1023 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 337572 T1023 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 337572 T1023 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 337572 T1023 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:34148/collection1/
[junit4] 2> 337573 T1023 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 337573 T1023 oasc.SyncStrategy.syncToMe http://127.0.0.1:34148/collection1/ has no replicas
[junit4] 2> 337573 T1023 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:34148/collection1/ shard1
[junit4] 2> 337574 T1023 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 337913 T1003 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 337919 T1002 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> 337919 T1009 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> 337919 T1022 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> 337930 T1023 oasc.ZkController.register We are http://127.0.0.1:34148/collection1/ and leader is http://127.0.0.1:34148/collection1/
[junit4] 2> 337930 T1023 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:34148
[junit4] 2> 337931 T1023 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 337931 T1023 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 337931 T1023 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 337933 T1023 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 337934 T980 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 337935 T980 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 338001 T980 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 338003 T980 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:44662
[junit4] 2> 338004 T980 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 338004 T980 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 338005 T980 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1382194859234
[junit4] 2> 338005 T980 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1382194859234/'
[junit4] 2> 338013 T980 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-1382194859234/solr.xml
[junit4] 2> 338062 T980 oasc.CoreContainer.<init> New CoreContainer -1146168386
[junit4] 2> 338062 T980 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1382194859234/]
[junit4] 2> 338064 T980 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 338064 T980 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 338065 T980 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 338066 T980 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 338066 T980 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 338067 T980 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 338067 T980 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 338068 T980 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 338069 T980 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 338070 T980 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 338070 T980 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 338071 T980 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:37417/solr
[junit4] 2> 338072 T980 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 338073 T980 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 338075 T1036 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e45b8b93 name:ZooKeeperConnection Watcher:127.0.0.1:37417 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 338075 T980 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 338078 T980 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 338079 T1038 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d01180ed name:ZooKeeperConnection Watcher:127.0.0.1:37417/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 338079 T980 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 338082 T980 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 339085 T980 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44662_
[junit4] 2> 339088 T980 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44662_
[junit4] 2> 339090 T1002 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 339090 T1009 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 339090 T1022 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 339091 T1038 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 339096 T1039 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 339096 T1039 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 339098 T1039 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 339427 T1003 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 339428 T1003 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:34148",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:34148_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 339431 T1003 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:44662",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44662_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 339431 T1003 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 339431 T1003 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 339434 T1002 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> 339435 T1038 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> 339435 T1009 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> 339434 T1022 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> 340099 T1039 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 340099 T1039 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1382194859234/collection1
[junit4] 2> 340099 T1039 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 340100 T1039 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 340100 T1039 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 340101 T1039 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1382194859234/collection1/'
[junit4] 2> 340102 T1039 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-1382194859234/collection1/lib/classes/' to classloader
[junit4] 2> 340102 T1039 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-1382194859234/collection1/lib/README' to classloader
[junit4] 2> 340135 T1039 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 340169 T1039 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 340170 T1039 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 340177 T1039 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 340512 T1039 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 340512 T1039 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 340513 T1039 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 340518 T1039 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 340520 T1039 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 340530 T1039 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 340534 T1039 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 340538 T1039 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 340539 T1039 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 340539 T1039 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 340539 T1039 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 340540 T1039 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 340541 T1039 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 340541 T1039 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 340541 T1039 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1382194859234/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty2/
[junit4] 2> 340542 T1039 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7ec6e7e7
[junit4] 2> 340543 T1039 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty2
[junit4] 2> 340543 T1039 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty2/index/
[junit4] 2> 340543 T1039 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 340544 T1039 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty2/index
[junit4] 2> 340544 T1039 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31137590061266096]
[junit4] 2> 340545 T1039 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@851f1944 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b8e1ca9),segFN=segments_1,generation=1}
[junit4] 2> 340545 T1039 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 340547 T1039 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 340548 T1039 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 340548 T1039 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 340549 T1039 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 340549 T1039 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 340549 T1039 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 340550 T1039 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 340550 T1039 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 340550 T1039 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 340551 T1039 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 340551 T1039 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 340552 T1039 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 340552 T1039 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 340553 T1039 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 340553 T1039 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 340554 T1039 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 340557 T1039 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 340557 T1039 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 340558 T1039 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=201473244, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 340558 T1039 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@851f1944 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9b8e1ca9),segFN=segments_1,generation=1}
[junit4] 2> 340559 T1039 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 340559 T1039 oass.SolrIndexSearcher.<init> Opening Searcher@104a6673 main
[junit4] 2> 340562 T1040 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@104a6673 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 340563 T1039 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 340564 T1039 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:44662 collection:collection1 shard:shard2
[junit4] 2> 340565 T1039 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 340569 T1039 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 340571 T1039 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 340571 T1039 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 340572 T1039 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:44662/collection1/
[junit4] 2> 340572 T1039 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 340572 T1039 oasc.SyncStrategy.syncToMe http://127.0.0.1:44662/collection1/ has no replicas
[junit4] 2> 340573 T1039 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:44662/collection1/ shard2
[junit4] 2> 340573 T1039 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 340940 T1003 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 341025 T1022 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> 341026 T1038 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> 341026 T1002 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> 341025 T1009 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> 341028 T1039 oasc.ZkController.register We are http://127.0.0.1:44662/collection1/ and leader is http://127.0.0.1:44662/collection1/
[junit4] 2> 341028 T1039 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:44662
[junit4] 2> 341029 T1039 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 341029 T1039 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 341030 T1039 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 341031 T1039 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 341032 T980 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 341033 T980 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 341098 T980 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 341100 T980 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59743
[junit4] 2> 341100 T980 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 341101 T980 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 341102 T980 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-1382194862332
[junit4] 2> 341102 T980 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-1382194862332/'
[junit4] 2> 341110 T980 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1382194862332/solr.xml
[junit4] 2> 341141 T980 oasc.CoreContainer.<init> New CoreContainer 1414998140
[junit4] 2> 341142 T980 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-1382194862332/]
[junit4] 2> 341143 T980 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 341144 T980 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 341144 T980 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 341145 T980 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 341146 T980 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 341146 T980 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 341147 T980 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 341147 T980 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 341148 T980 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 341149 T980 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 341150 T980 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 341150 T980 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:37417/solr
[junit4] 2> 341151 T980 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 341152 T980 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 341154 T1052 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8295e450 name:ZooKeeperConnection Watcher:127.0.0.1:37417 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 341154 T980 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 341156 T980 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 341159 T1054 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6af10321 name:ZooKeeperConnection Watcher:127.0.0.1:37417/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 341159 T980 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 341163 T980 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 342167 T980 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59743_
[junit4] 2> 342192 T980 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59743_
[junit4] 2> 342203 T1022 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 342203 T1054 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 342204 T1038 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 342203 T1002 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 342203 T1009 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 342210 T1055 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 342210 T1055 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 342211 T1055 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 342531 T1003 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 342532 T1003 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:44662",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:44662_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 342536 T1003 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59743",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:59743_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node3"}
[junit4] 2> 342536 T1003 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 342536 T1003 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 342539 T1022 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> 342539 T1054 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> 342539 T1009 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> 342539 T1038 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> 342540 T1002 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> 343211 T1055 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 343212 T1055 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-1382194862332/collection1
[junit4] 2> 343212 T1055 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 343213 T1055 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 343213 T1055 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 343214 T1055 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-1382194862332/collection1/'
[junit4] 2> 343215 T1055 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-1382194862332/collection1/lib/classes/' to classloader
[junit4] 2> 343215 T1055 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-1382194862332/collection1/lib/README' to classloader
[junit4] 2> 343246 T1055 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 343278 T1055 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 343279 T1055 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 343285 T1055 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 343638 T1055 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 343639 T1055 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 343639 T1055 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 343644 T1055 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 343647 T1055 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 343657 T1055 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 343662 T1055 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 343667 T1055 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 343668 T1055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 343668 T1055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 343668 T1055 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 343669 T1055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 343670 T1055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 343670 T1055 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 343670 T1055 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-1382194862332/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty3/
[junit4] 2> 343671 T1055 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7ec6e7e7
[junit4] 2> 343672 T1055 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty3
[junit4] 2> 343672 T1055 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty3/index/
[junit4] 2> 343672 T1055 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty3/index' doesn't exist. Creating new index...
[junit4] 2> 343673 T1055 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1382194852702/jetty3/index
[junit4] 2> 343673 T1055 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31137590061266096]
[junit4] 2> 343674 T1055 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@86130749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e2ebb909),segFN=segments_1,generation=1}
[junit4] 2> 343674 T1055 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 343677 T1055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 343678 T1055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 343678 T1055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 343679 T1055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 343679 T1055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 343679 T1055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 343680 T1055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 343680 T1055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 343680 T1055 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 343681 T1055 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 343682 T1055 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 343682 T1055 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 343682 T1055 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 343683 T1055 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 343683 T1055 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 343684 T1055 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 343687 T1055 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 343687 T1055 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 343688 T1055 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=201473244, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 343689 T1055 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@86130749 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e2ebb909),segFN=segments_1,generation=1}
[junit4] 2> 343689 T1055 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 343689 T1055 oass.SolrIndexSearcher.<init> Opening Searcher@6862bdba main
[junit4] 2> 343692 T1056 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6862bdba main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 343694 T1055 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 343694 T1055 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:59743 collection:collection1 shard:shard1
[junit4] 2> 343698 T1055 oasc.ZkController.register We are http://127.0.0.1:59743/collection1/ and leader is http://127.0.0.1:34148/collection1/
[junit4] 2> 343699 T1055 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:59743
[junit4] 2> 343699 T1055 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 343699 T1055 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C1045 name=collection1 org.apache.solr.core.SolrCore@b67cbb12 url=http://127.0.0.1:59743/collection1 node=127.0.0.1:59743_ C1045_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:59743, core=collection1, node_name=127.0.0.1:59743_}
[junit4] 2> 343700 T1057 C1045 P59743 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 343700 T1055 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 343700 T1057 C1045 P59743 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4] 2> 343701 T1057 C1045 P59743 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4] 2> 343701 T1057 C1045 P59743 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 343701 T980 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 343702 T980 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 343704 T1016 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true
[junit4] 2> 343784 T980 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 343786 T980 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60711
[junit4] 2> 343787 T980 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 343788 T980 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 343788 T980 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1382194865002
[junit4] 2> 343789 T980 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1382194865002/'
[junit4] 2> 343797 T980 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-1382194865002/solr.xml
[junit4] 2> 343828 T980 oasc.CoreContainer.<init> New CoreContainer -244931641
[junit4] 2> 343828 T980 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.BasicDistributedZk2Test-j
[...truncated too long message...]
(0)
[junit4] 1> /solr/live_nodes/127.0.0.1:37631_ (0)
[junit4] 1> /solr/live_nodes/127.0.0.1:60711_ (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> "state":"active",
[junit4] 1> "base_url":"http://127.0.0.1:37631",
[junit4] 1> "core":"collection1",
[junit4] 1> "node_name":"127.0.0.1:37631_",
[junit4] 1> "leader":"true"}}}},
[junit4] 1> "maxShardsPerNode":"1",
[junit4] 1> "router":{"name":"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> "state":"active",
[junit4] 1> "base_url":"http://127.0.0.1:34148",
[junit4] 1> "core":"onenodecollectioncore",
[junit4] 1> "roles":"none",
[junit4] 1> "node_name":"127.0.0.1:34148_",
[junit4] 1> "leader":"true"}}}},
[junit4] 1> "maxShardsPerNode":"1",
[junit4] 1> "router":{"name":"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> "state":"active",
[junit4] 1> "base_url":"http://127.0.0.1:34148",
[junit4] 1> "core":"collection1",
[junit4] 1> "node_name":"127.0.0.1:34148_",
[junit4] 1> "leader":"true"},
[junit4] 1> "core_node3":{
[junit4] 1> "state":"active",
[junit4] 1> "base_url":"http://127.0.0.1:59743",
[junit4] 1> "core":"collection1",
[junit4] 1> "node_name":"127.0.0.1:59743_"}}},
[junit4] 1> "shard2":{
[junit4] 1> "range":"0-7fffffff",
[junit4] 1> "state":"active",
[junit4] 1> "replicas":{
[junit4] 1> "core_node2":{
[junit4] 1> "state":"active",
[junit4] 1> "base_url":"http://127.0.0.1:44662",
[junit4] 1> "core":"collection1",
[junit4] 1> "node_name":"127.0.0.1:44662_",
[junit4] 1> "leader":"true"},
[junit4] 1> "core_node4":{
[junit4] 1> "state":"active",
[junit4] 1> "base_url":"http://127.0.0.1:60711",
[junit4] 1> "core":"collection1",
[junit4] 1> "node_name":"127.0.0.1:60711_"}}}},
[junit4] 1> "maxShardsPerNode":"1",
[junit4] 1> "router":{"name":"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=7A991F138C19AA7A -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_NI -Dtests.timezone=Turkey -Dtests.file.encoding=US-ASCII
[junit4] ERROR 29.2s J1 | BasicDistributedZk2Test.testDistribSearch <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrServer$RemoteSolrException: Expected content type application/octet-stream but got text/html;charset=ISO-8859-1. <html>
[junit4] > <head>
[junit4] > <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
[junit4] > <title>Error 404 Can not find: /onenodecollectioncore/update</title>
[junit4] > </head>
[junit4] > <body>
[junit4] > <h2>HTTP ERROR: 404</h2>
[junit4] > <p>Problem accessing /onenodecollectioncore/update. Reason:
[junit4] > <pre> Can not find: /onenodecollectioncore/update</pre></p>
[junit4] > <hr /><i><small>Powered by Jetty://</small></i>
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] >
[junit4] > </body>
[junit4] > </html>
[junit4] > at __randomizedtesting.SeedInfo.seed([7A991F138C19AA7A:FB7F910BFB46CA46]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:428)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[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> 360593 T980 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 29198 T979 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> 360628 T1054 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 361739 T1071 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: test params are: codec=Lucene45: {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=es_NI, timezone=Turkey
[junit4] 2> NOTE: Linux 3.8.0-31-generic x86/IBM Corporation 1.7.0 (32-bit)/cpus=8,threads=3,free=55499248,total=105054208
[junit4] 2> NOTE: All tests run in this JVM: [TestOmitPositions, TestQueryTypes, AddBlockUpdateTest, HdfsRecoveryZkTest, TestAnalyzedSuggestions, DefaultValueUpdateProcessorTest, NoCacheHeaderTest, OpenCloseCoreStressTest, HighlighterConfigTest, SpellCheckCollatorTest, TestSolrXmlPersistence, ChaosMonkeySafeLeaderTest, MultiTermTest, TestCopyFieldCollectionResource, SortByFunctionTest, PreAnalyzedFieldTest, TestDefaultSearchFieldResource, TestDistributedSearch, CurrencyFieldXmlFileTest, TestRandomFaceting, TimeZoneUtilsTest, TestFieldTypeResource, TestFoldingMultitermQuery, TestWordDelimiterFilterFactory, HdfsUnloadDistributedZkTest, TestIndexSearcher, TestIndexingPerformance, BasicDistributedZk2Test]
[junit4] Completed on J1 in 30.48s, 1 test, 1 error <<< FAILURES!
[...truncated 769 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:428: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:421: 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:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:483: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1263: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:906: There were test failures: 327 suites, 1453 tests, 1 error, 35 ignored (6 assumptions)
Total time: 46 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
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.7.0_45) - Build # 7857
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/7857/
Java: 32bit/jdk1.7.0_45 -server -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate
Error Message:
expected:<[in]active> but was:<[]active>
Stack Trace:
org.junit.ComparisonFailure: expected:<[in]active> but was:<[]active>
at __randomizedtesting.SeedInfo.seed([ECC4ED6DDC1BE260:3CA6602182CE38E4]:0)
at org.junit.Assert.assertEquals(Assert.java:125)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate(SliceStateUpdateTest.java:183)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:744)
Build Log:
[...truncated 10349 lines...]
[junit4] Suite: org.apache.solr.cloud.SliceStateUpdateTest
[junit4] 2> 922579 T3762 oas.SolrTestCaseJ4.setUp ###Starting testSliceStateUpdate
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831
[junit4] 2> 922580 T3762 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 922580 T3763 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 922681 T3762 oasc.ZkTestServer.run start zk server on port:35149
[junit4] 2> 922681 T3762 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 922834 T3769 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@17fe1e6 name:ZooKeeperConnection Watcher:127.0.0.1:35149 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 922834 T3762 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 922834 T3762 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 922838 T3762 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 922839 T3771 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16a8abf name:ZooKeeperConnection Watcher:127.0.0.1:35149/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 922839 T3762 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 922839 T3762 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 922841 T3762 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 922842 T3762 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 922844 T3762 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 922845 T3762 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 922845 T3762 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 922848 T3762 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> 922849 T3762 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 922851 T3762 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> 922851 T3762 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 922854 T3762 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> 922854 T3762 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 922856 T3762 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> 922856 T3762 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 922858 T3762 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> 922858 T3762 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 922860 T3762 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> 922860 T3762 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 922862 T3762 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> 922862 T3762 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 922864 T3762 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> 922864 T3762 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 922866 T3762 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> 922866 T3762 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 922868 T3762 oasc.SliceStateUpdateTest.setUp ####SETUP_START testSliceStateUpdate
[junit4] 2> 922869 T3762 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 922870 T3773 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@75c5bb name:ZooKeeperConnection Watcher:127.0.0.1:35149/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 922870 T3762 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 922870 T3762 oascc.SolrZkClient.makePath makePath: /collections/testcore
[junit4] 2> 922871 T3762 oascc.SolrZkClient.makePath makePath: /collections/testcore/shards
[junit4] 2> 922874 T3762 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 922874 T3762 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 922874 T3762 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 922883 T3762 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 922915 T3762 oasc.CoreContainer.<init> New CoreContainer 27092846
[junit4] 2> 922915 T3762 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 922916 T3762 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 922916 T3762 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 922922 T3762 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 922949 T3762 oasc.CoreContainer.<init> New CoreContainer 3584491
[junit4] 2> 922949 T3762 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 922950 T3762 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 922950 T3762 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 922950 T3762 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 922950 T3762 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 922951 T3762 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 922951 T3762 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 922951 T3762 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 922951 T3762 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 922951 T3762 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 922952 T3762 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 922953 T3762 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 922953 T3762 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:35149/solr
[junit4] 2> 922953 T3762 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 922954 T3762 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 922955 T3775 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18b4ef5 name:ZooKeeperConnection Watcher:127.0.0.1:35149 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 922955 T3762 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 922957 T3762 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 922958 T3777 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@184260 name:ZooKeeperConnection Watcher:127.0.0.1:35149/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 922958 T3762 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 922959 T3762 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 922961 T3762 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 922964 T3762 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 922966 T3762 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 922966 T3762 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 922968 T3762 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 922971 T3762 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 922972 T3762 oasc.Overseer.start Overseer (id=90583943402815492-127.0.0.1:8983_solr-n_0000000000) starting
[junit4] 2> 922974 T3762 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 922976 T3779 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 922976 T3762 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 922977 T3762 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 922978 T3762 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 922980 T3778 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 922982 T3780 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 922982 T3780 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 922984 T3780 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 924482 T3778 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 924483 T3778 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":null,
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 924483 T3778 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 924486 T3777 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> 924984 T3780 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 924984 T3780 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 924985 T3780 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 924985 T3780 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 924985 T3780 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 924986 T3780 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 924987 T3780 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 924987 T3780 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 925039 T3780 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 925085 T3780 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 925085 T3780 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 925091 T3780 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 925378 T3780 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 925378 T3780 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 925379 T3780 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 925383 T3780 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 925385 T3780 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 925393 T3780 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 925396 T3780 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 925398 T3780 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 925399 T3780 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 925399 T3780 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 925399 T3780 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 925400 T3780 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 925400 T3780 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 925400 T3780 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 925400 T3780 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/
[junit4] 2> 925401 T3780 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19b88d8
[junit4] 2> 925401 T3780 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2
[junit4] 2> 925402 T3780 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index/
[junit4] 2> 925402 T3780 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index' doesn't exist. Creating new index...
[junit4] 2> 925402 T3780 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index
[junit4] 2> 925402 T3780 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@11b1cfd
[junit4] 2> 925403 T3780 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@a995cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7b2fbc)),segFN=segments_1,generation=1}
[junit4] 2> 925404 T3780 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 925404 T3780 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 925407 T3780 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 925407 T3780 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 925408 T3780 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 925408 T3780 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 925408 T3780 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 925408 T3780 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 925409 T3780 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 925409 T3780 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
[junit4] 2> 925409 T3780 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
[junit4] 2> 925409 T3780 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 925410 T3780 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 925410 T3780 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 925410 T3780 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 925411 T3780 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 925411 T3780 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 925411 T3780 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 925412 T3780 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 925412 T3780 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 925413 T3780 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 925413 T3780 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 925413 T3780 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 925414 T3780 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 925414 T3780 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 925414 T3780 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 925415 T3780 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 925415 T3780 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 925416 T3780 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 925416 T3780 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 925417 T3780 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 925417 T3780 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 925417 T3780 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 925418 T3780 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 925418 T3780 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 925419 T3780 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 925419 T3780 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 925422 T3780 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 925424 T3780 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 925425 T3780 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML]
[junit4] 2> 925427 T3780 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 925427 T3780 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 925428 T3780 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7882998303801543]
[junit4] 2> 925429 T3780 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@a995cc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7b2fbc)),segFN=segments_1,generation=1}
[junit4] 2> 925429 T3780 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 925429 T3780 oass.SolrIndexSearcher.<init> Opening Searcher@2efd89 main
[junit4] 2> 925429 T3780 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 925434 T3780 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 925449 T3781 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2efd89 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 925453 T3780 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 925453 T3780 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 925454 T3780 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 925459 T3780 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 925460 T3780 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 925460 T3780 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 925460 T3780 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 925460 T3780 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 925988 T3778 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 925992 T3777 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> 926014 T3780 oasc.ZkController.register We are http://127.0.0.1:8983/solr/collection1/ and leader is http://127.0.0.1:8983/solr/collection1/
[junit4] 2> 926015 T3780 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 926015 T3780 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 926015 T3780 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 926015 T3780 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 926017 T3780 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 926018 T3762 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 926020 T3762 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 926020 T3762 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 926021 T3762 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 926021 T3762 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 926021 T3762 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 926021 T3762 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 926022 T3762 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 926022 T3762 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 926022 T3762 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 926023 T3762 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 926024 T3762 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 926024 T3762 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:35149/solr
[junit4] 2> 926024 T3762 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 926025 T3762 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 926026 T3784 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1723fc4 name:ZooKeeperConnection Watcher:127.0.0.1:35149 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 926026 T3762 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 926028 T3762 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 926029 T3786 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@454136 name:ZooKeeperConnection Watcher:127.0.0.1:35149/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 926030 T3762 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 926032 T3762 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 927034 T3762 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 927035 T3762 oasc.ZkController.createEphemeralLiveNode Found a previous node that still exists while trying to register a new live node /live_nodes/127.0.0.1:8983_solr - removing existing node to create another.
[junit4] 2> 927035 T3777 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 927035 T3762 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 927035 T3786 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 927037 T3786 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 927037 T3777 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 927040 T3787 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 927040 T3787 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 927041 T3787 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 927041 T3787 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 927041 T3787 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 927042 T3787 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 927042 T3787 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 927042 T3787 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 927043 T3787 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 927043 T3787 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 927043 T3787 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 927084 T3787 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_46
[junit4] 2> 927131 T3787 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 927132 T3787 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 927138 T3787 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 927439 T3787 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 927439 T3787 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 927440 T3787 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 927445 T3787 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 927447 T3787 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 927455 T3787 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 927457 T3787 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 927460 T3787 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 927460 T3787 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 927461 T3787 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 927461 T3787 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 927461 T3787 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 927462 T3787 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 927462 T3787 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 927462 T3787 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/
[junit4] 2> 927462 T3787 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@19b88d8
[junit4] 2> 927463 T3787 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2
[junit4] 2> 927463 T3787 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index/
[junit4] 2> 927463 T3787 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index' doesn't exist. Creating new index...
[junit4] 2> 927463 T3787 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index
[junit4] 2> 927464 T3787 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@198c4d6
[junit4] 2> 927464 T3787 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@161abb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a3035)),segFN=segments_1,generation=1}
[junit4] 2> 927464 T3787 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 927465 T3787 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 927467 T3787 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 927467 T3787 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 927467 T3787 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 927468 T3787 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 927468 T3787 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 927468 T3787 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 927468 T3787 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 927468 T3787 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
[junit4] 2> 927468 T3787 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
[junit4] 2> 927469 T3787 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 927469 T3787 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 927469 T3787 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 927469 T3787 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 927470 T3787 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 927470 T3787 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 927470 T3787 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 927470 T3787 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 927471 T3787 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 927471 T3787 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 927472 T3787 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 927472 T3787 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 927472 T3787 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 927472 T3787 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 927473 T3787 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 927473 T3787 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 927474 T3787 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 927474 T3787 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 927474 T3787 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 927475 T3787 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 927475 T3787 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 927475 T3787 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 927476 T3787 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 927476 T3787 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 927477 T3787 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 927477 T3787 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 927479 T3787 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 927480 T3787 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 927481 T3787 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML]
[junit4] 2> 927483 T3787 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 927483 T3787 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 927483 T3787 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7882998303801543]
[junit4] 2> 927484 T3787 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@161abb lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5a3035)),segFN=segments_1,generation=1}
[junit4] 2> 927484 T3787 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 927484 T3787 oass.SolrIndexSearcher.<init> Opening Searcher@6793ec main
[junit4] 2> 927485 T3787 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 927485 T3787 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 927491 T3788 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6793ec main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 927496 T3778 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 927496 T3787 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 927496 T3778 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":null,
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 927496 T3787 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 927498 T3778 oasc.Overseer$ClusterStateUpdater.updateState node=core_node1 is already registered
[junit4] 2> 927498 T3787 oasc.ZkController.register We are http://127.0.0.1:8983/solr/collection1/ and leader is http://127.0.0.1:8983/solr/collection1/
[junit4] 2> 927498 T3778 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":null,
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 927499 T3787 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 927499 T3787 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 927499 T3778 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 927499 T3787 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 927499 T3787 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 927500 T3787 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 927501 T3762 oasc.SliceStateUpdateTest.setUp ####SETUP_END testSliceStateUpdate
[junit4] 2> 927501 T3778 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":null,
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 927502 T3762 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 927503 T3791 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2e26d6 name:ZooKeeperConnection Watcher:127.0.0.1:35149/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 927503 T3762 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 927504 T3777 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> 927504 T3786 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> 965012 T3762 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=27092846
[junit4] 2> 965037 T3778 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 965038 T3778 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 965038 T3778 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 965040 T3786 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> 965040 T3777 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> 967014 T3762 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 967015 T3762 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 967017 T3762 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7cc940
[junit4] 2> 967022 T3762 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 967022 T3762 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 967023 T3762 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 967023 T3762 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 967024 T3762 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 967024 T3762 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 967024 T3762 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index;done=false>>]
[junit4] 2> 967025 T3762 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index
[junit4] 2> 967025 T3762 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2;done=false>>]
[junit4] 2> 967025 T3762 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2
[junit4] 2> 967026 T3778 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90583943402815492-127.0.0.1:8983_solr-n_0000000000) am no longer a leader.
[junit4] 2> 967027 T3762 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=3584491
[junit4] 2> 967028 T3786 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 967029 T3786 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4] 2> 967029 T3786 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4] 2> 967029 T3786 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 967029 T3786 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 967030 T3786 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 967034 T3786 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 967036 T3786 oasc.Overseer.start Overseer (id=90583943402815494-127.0.0.1:8983_solr-n_0000000001) starting
[junit4] 2> 967039 T3794 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 967039 T3793 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 967040 T3793 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 967041 T3793 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":null,
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 967045 T3786 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> 968027 T3762 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 968028 T3762 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 968029 T3762 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1158257
[junit4] 2> 968037 T3762 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 968038 T3762 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 968038 T3762 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 968038 T3762 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 968039 T3762 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 968039 T3762 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 968040 T3762 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2;done=false>>]
[junit4] 2> 968040 T3762 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2
[junit4] 2> 968040 T3762 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index;done=false>>]
[junit4] 2> 968040 T3762 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SliceStateUpdateTest-1382201284831/data2/index
[junit4] 2> 968041 T3793 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90583943402815494-127.0.0.1:8983_solr-n_0000000001) am no longer a leader.
[junit4] 2> 968043 T3762 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:35149 35149
[junit4] 2> 968244 T3762 oas.SolrTestCaseJ4.tearDown ###Ending testSliceStateUpdate
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SliceStateUpdateTest -Dtests.method=testSliceStateUpdate -Dtests.seed=ECC4ED6DDC1BE260 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en_PH -Dtests.timezone=America/Mendoza -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 45.7s J0 | SliceStateUpdateTest.testSliceStateUpdate <<<
[junit4] > Throwable #1: org.junit.ComparisonFailure: expected:<[in]active> but was:<[]active>
[junit4] > at __randomizedtesting.SeedInfo.seed([ECC4ED6DDC1BE260:3CA6602182CE38E4]:0)
[junit4] > at org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate(SliceStateUpdateTest.java:183)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 968270 T3762 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 45698 T3761 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> 969542 T3786 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: test params are: codec=Lucene41, sim=DefaultSimilarity, locale=en_PH, timezone=America/Mendoza
[junit4] 2> NOTE: Linux 3.8.0-31-generic i386/Oracle Corporation 1.7.0_45 (32-bit)/cpus=8,threads=1,free=141962136,total=349175808
[junit4] 2> NOTE: All tests run in this JVM: [CacheHeaderTest, TestLMJelinekMercerSimilarityFactory, ShardRoutingTest, TestJmxMonitoredMap, HdfsBasicDistributedZkTest, TestDocSet, TestNoOpRegenerator, ShowFileRequestHandlerTest, CircularListTest, TestBadConfig, WordBreakSolrSpellCheckerTest, TestSchemaSimilarityResource, FieldMutatingUpdateProcessorTest, TestFaceting, TestSolrXml, FileBasedSpellCheckerTest, SpatialFilterTest, QueryParsingTest, TestSolrXmlPersistor, TestSolrQueryParserDefaultOperatorResource, FastVectorHighlighterTest, PrimitiveFieldTypeTest, CSVRequestHandlerTest, TestRealTimeGet, MoreLikeThisHandlerTest, SynonymTokenizerTest, BasicFunctionalityTest, LoggingHandlerTest, LukeRequestHandlerTest, TestNRTOpen, TestSchemaNameResource, DateMathParserTest, SolrCoreTest, OverseerTest, ScriptEngineTest, SpellPossibilityIteratorTest, TermsComponentTest, HdfsLockFactoryTest, TestFunctionQuery, TestDynamicFieldCollectionResource, TestSystemIdResolver, DistributedTermsComponentTest, TestAnalyzeInfixSuggestions, TestCloudManagedSchemaAddField, JsonLoaderTest, InfoHandlerTest, AnalysisAfterCoreReloadTest, TestReplicationHandler, CoreAdminCreateDiscoverTest, TestExtendedDismaxParser, TestLMDirichletSimilarityFactory, AutoCommitTest, TestLuceneMatchVersion, ResponseLogComponentTest, LeaderElectionTest, TestRemoteStreaming, TestSchemaResource, CachingDirectoryFactoryTest, MBeansHandlerTest, IndexSchemaTest, ZkCLITest, SliceStateTest, CoreMergeIndexesAdminHandlerTest, TestSolrQueryParser, TestFieldResource, UpdateRequestProcessorFactoryTest, ReturnFieldsTest, TestConfig, UpdateParamsTest, BJQParserTest, TestValueSourceCache, SolrInfoMBeanTest, TestRecoveryHdfs, RegexBoostProcessorTest, TestMergePolicyConfig, HdfsUnloadDistributedZkTest, SolrXmlInZkTest, TermVectorComponentTest, SuggesterFSTTest, BasicDistributedZkTest, UnloadDistributedZkTest, SyncSliceTest, LeaderElectionIntegrationTest, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, TestRandomFaceting, ShardRoutingCustomTest, SliceStateUpdateTest]
[junit4] Completed on J0 in 47.21s, 1 test, 1 failure <<< FAILURES!
[...truncated 454 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:428: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:408: 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:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:483: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1263: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:906: There were test failures: 327 suites, 1453 tests, 1 failure, 36 ignored (7 assumptions)
Total time: 42 minutes 32 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_45 -server -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure