Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11690/
Java: 32bit/jdk1.9.0-ea-b34 -server -XX:+UseSerialGC (asserts: false)
1 tests failed.
FAILED: org.apache.solr.handler.TestSolrConfigHandlerCloud.testDistribSearch
Error Message:
Could not get expected value P val for path [response, params, y, p] full
output { "responseHeader":{ "status":0, "QTime":0}, "response":{
"znodeVersion":2, "params":{ "x":{ "a":"A val",
"b":"B val", "":{"v":0}}, "y":{ "c":"CY val modified",
"b":"BY val", "e":"EY val", "":{"v":0}}}}}
Stack Trace:
java.lang.AssertionError: Could not get expected value P val for path
[response, params, y, p] full output {
"responseHeader":{
"status":0,
"QTime":0},
"response":{
"znodeVersion":2,
"params":{
"x":{
"a":"A val",
"b":"B val",
"":{"v":0}},
"y":{
"c":"CY val modified",
"b":"BY val",
"e":"EY val",
"":{"v":0}}}}}
at
__randomizedtesting.SeedInfo.seed([8E63712912535E9D:F85FF31650C3EA1]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:243)
at
org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:253)
at
org.apache.solr.handler.TestSolrConfigHandlerCloud.doTest(TestSolrConfigHandlerCloud.java:61)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 9362 lines...]
[junit4] Suite: org.apache.solr.handler.TestSolrConfigHandlerCloud
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/init-core-data-001
[junit4] 2> 265826 T1849 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (false)
[junit4] 2> 265826 T1849 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /wgc/e
[junit4] 2> 265828 T1849 oas.SolrTestCaseJ4.setUp ###Starting
testDistribSearch
[junit4] 2> 265828 T1849 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 265829 T1850 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 265929 T1849 oasc.ZkTestServer.run start zk server on
port:57680
[junit4] 2> 265929 T1849
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 265930 T1849 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 265934 T1857 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1e80a74 name:ZooKeeperConnection
Watcher:127.0.0.1:57680 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 265935 T1849 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 265935 T1849 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 265935 T1849 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 265937 T1849
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 265938 T1849 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 265938 T1860 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@683122 name:ZooKeeperConnection
Watcher:127.0.0.1:57680/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 265939 T1849 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 265939 T1849 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 265939 T1849 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 265941 T1849 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 265942 T1849 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 265943 T1849 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 265945 T1849 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 265945 T1849 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 265947 T1849 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 265947 T1849 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 265949 T1849 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 265949 T1849 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 265956 T1849 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 265956 T1849 oascc.SolrZkClient.makePath makePath:
/configs/conf1/stopwords.txt
[junit4] 2> 265958 T1849 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 265959 T1849 oascc.SolrZkClient.makePath makePath:
/configs/conf1/protwords.txt
[junit4] 2> 265960 T1849 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 265960 T1849 oascc.SolrZkClient.makePath makePath:
/configs/conf1/currency.xml
[junit4] 2> 265962 T1849 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 265962 T1849 oascc.SolrZkClient.makePath makePath:
/configs/conf1/enumsConfig.xml
[junit4] 2> 265964 T1849 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 265964 T1849 oascc.SolrZkClient.makePath makePath:
/configs/conf1/open-exchange-rates.json
[junit4] 2> 265966 T1849 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 265966 T1849 oascc.SolrZkClient.makePath makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 265968 T1849 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 265969 T1849 oascc.SolrZkClient.makePath makePath:
/configs/conf1/old_synonyms.txt
[junit4] 2> 265970 T1849 oasc.AbstractZkTestCase.putConfig put
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 265971 T1849 oascc.SolrZkClient.makePath makePath:
/configs/conf1/synonyms.txt
[junit4] 2> 266346 T1849 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 266351 T1849 oejs.AbstractConnector.doStart Started
[email protected]:40456
[junit4] 2> 266351 T1849 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 266352 T1849 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 266352 T1849 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-002
[junit4] 2> 266353 T1849 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-002/'
[junit4] 2> 266368 T1849 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-002/solr.xml
[junit4] 2> 266377 T1849 oasc.CoreContainer.<init> New CoreContainer
33367006
[junit4] 2> 266378 T1849 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-002/]
[junit4] 2> 266379 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 266379 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 266379 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 266380 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 266380 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 266380 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 266381 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 266381 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 266381 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 266382 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 266382 T1849 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 266383 T1849 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 266383 T1849 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 266383 T1849 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 266384 T1849 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:57680/solr
[junit4] 2> 266384 T1849 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 266385 T1849
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 266385 T1849 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 266387 T1872 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1308161 name:ZooKeeperConnection
Watcher:127.0.0.1:57680 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 266387 T1849 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 266387 T1849 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 266389 T1849 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 266390 T1875 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@11423a2 name:ZooKeeperConnection
Watcher:127.0.0.1:57680/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 266390 T1849 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 266392 T1849 oascc.SolrZkClient.makePath makePath:
/overseer/queue
[junit4] 2> 266393 T1849 oascc.SolrZkClient.makePath makePath:
/overseer/collection-queue-work
[junit4] 2> 266395 T1849 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-running
[junit4] 2> 266396 T1849 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-completed
[junit4] 2> 266398 T1849 oascc.SolrZkClient.makePath makePath:
/overseer/collection-map-failure
[junit4] 2> 266400 T1849 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 266401 T1849 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40456_wgc%2Fe
[junit4] 2> 266402 T1849 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:40456_wgc%2Fe
[junit4] 2> 266404 T1849 oascc.SolrZkClient.makePath makePath:
/overseer_elect
[junit4] 2> 266405 T1849 oascc.SolrZkClient.makePath makePath:
/overseer_elect/election
[junit4] 2> 266406 T1849 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 266419 T1849 oasc.OverseerElectionContext.runLeaderProcess I
am going to be the leader 127.0.0.1:40456_wgc%2Fe
[junit4] 2> 266420 T1849 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4] 2> 266421 T1849 oasc.Overseer.start Overseer
(id=93098765642170371-127.0.0.1:40456_wgc%2Fe-n_0000000000) starting
[junit4] 2> 266423 T1849 oascc.SolrZkClient.makePath makePath:
/overseer/queue-work
[junit4] 2> 266428 T1849 oasc.OverseerAutoReplicaFailoverThread.<init>
Starting OverseerAutoReplicaFailoverThread
autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 266429 T1877 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4] 2> 266430 T1849 oascc.SolrZkClient.makePath makePath:
/clusterstate.json
[junit4] 2> 266431 T1849 oascc.SolrZkClient.makePath makePath:
/aliases.json
[junit4] 2> 266432 T1849
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 266435 T1876 oasc.Overseer$ClusterStateUpdater.run Starting to
work on the main queue
[junit4] 2> 266436 T1879 oasc.ZkController.publish publishing
core=collection1 state=down collection=control_collection
[junit4] 2> 266436 T1879 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 266437 T1875 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 266437 T1879 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 266439 T1876 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40456/wgc/e";,
[junit4] 2> "node_name":"127.0.0.1:40456_wgc%2Fe",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 266439 T1876 oasco.ReplicaMutator.updateState Update state
numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40456/wgc/e";,
[junit4] 2> "node_name":"127.0.0.1:40456_wgc%2Fe",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 266439 T1876 oasco.ClusterStateMutator.createCollection
building a new cName: control_collection
[junit4] 2> 266439 T1876 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 266440 T1875 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> 267437 T1879 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 267438 T1879 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:control_collection
[junit4] 2> 267438 T1879 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 267438 T1879 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 267439 T1879 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 267439 T1879 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-002/collection1/'
[junit4] 2> 267440 T1879 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%208E63712912535E9D-001/tempDir-002/collection1/lib/.svn/'
to classloader
[junit4] 2> 267440 T1879 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%208E63712912535E9D-001/tempDir-002/collection1/lib/classes/'
to classloader
[junit4] 2> 267440 T1879 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%208E63712912535E9D-001/tempDir-002/collection1/lib/README'
to classloader
[junit4] 2> 267450 T1879 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 267452 T1879 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 267456 T1879 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 267459 T1879 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 267477 T1879 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 267478 T1879 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 267482 T1879 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 267558 T1879 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 267561 T1879 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 267562 T1879 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 267573 T1879 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 267576 T1879 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 267578 T1879 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 267579 T1879
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 267579 T1879
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 267579 T1879 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 267580 T1879
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 267580 T1879
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 267580 T1879 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection control_collection
[junit4] 2> 267580 T1879 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 267581 T1879 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-002/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/control/data/
[junit4] 2> 267581 T1879 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b44350
[junit4] 2> 267582 T1879 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/control/data
[junit4] 2> 267582 T1879 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/control/data/index/
[junit4] 2> 267582 T1879 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/control/data/index' doesn't exist. Creating
new index...
[junit4] 2> 267582 T1879 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/control/data/index
[junit4] 2> 267583 T1879 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=32,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4027344729375135]
[junit4] 2> 267583 T1879 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@15af6e4
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@189d6b7),segFN=segments_1,generation=1}
[junit4] 2> 267584 T1879 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 267595 T1879 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 267595 T1879 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 267595 T1879 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 267596 T1879 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 267596 T1879 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 267596 T1879 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 267596 T1879 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 267597 T1879 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 267597 T1879 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 267597 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 267598 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 267598 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 267598 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 267599 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 267599 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 267599 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 267600 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 267600 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 267601 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 267601 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 267601 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 267602 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 267602 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 267602 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 267603 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 267603 T1879 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 267603 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 267604 T1879 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 267606 T1879 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 267607 T1879 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 267608 T1879 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 267609 T1879 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 267610 T1879 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 267611 T1879 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 267611 T1879 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 267612 T1879 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=16, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=62.3125,
floorSegmentMB=1.4521484375, forceMergeDeletesPctAllowed=13.236879854633502,
segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 267612 T1879 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@15af6e4
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@189d6b7),segFN=segments_1,generation=1}
[junit4] 2> 267612 T1879 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 267613 T1879 oass.SolrIndexSearcher.<init> Opening
Searcher@937024[collection1] main
[junit4] 2> 267613 T1879 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/control_collection
[junit4] 2> 267614 T1879 oascc.ZkStateReader.readConfigName
path=/collections/control_collection configName=conf1 specified config exists
in ZooKeeper
[junit4] 2> 267614 T1879 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 267614 T1879
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 267615 T1879 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 267615 T1879 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 267615 T1879
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 267615 T1879 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 267616 T1879 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 267616 T1879 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 267616 T1879 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 267616 T1880 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@937024[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 267617 T1883 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:40456/wgc/e
collection:control_collection shard:shard1
[junit4] 2> 267617 T1849 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 267617 T1883 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 267617 T1849 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 267618 T1849
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 267619 T1849 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 267620 T1886 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@149bd10 name:ZooKeeperConnection
Watcher:127.0.0.1:57680/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 267620 T1849 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 267620 T1883 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 267620 T1849 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 267621 T1849
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 267621 T1883
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 267622 T1883 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> 267621 T1875 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> ASYNC NEW_CORE C233 name=collection1
org.apache.solr.core.SolrCore@bb0d76
url=http://127.0.0.1:40456/wgc/e/collection1 node=127.0.0.1:40456_wgc%2Fe
C233_STATE=coll:control_collection core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:40456/wgc/e, node_name=127.0.0.1:40456_wgc%2Fe,
state=down}
[junit4] 2> 267622 T1883 C233 P40456 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:40456/wgc/e/collection1/
[junit4] 2> 267622 T1876 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state
version: 1
[junit4] 2> 267623 T1883 C233 P40456 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 267623 T1883 C233 P40456 oasc.SyncStrategy.syncToMe
http://127.0.0.1:40456/wgc/e/collection1/ has no replicas
[junit4] 2> 267623 T1883 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:40456/wgc/e/collection1/ shard1
[junit4] 2> 267623 T1883 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 267623 T1849 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 267625 T1875 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 267626 T1876 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:40456/wgc/e";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 267728 T1875 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> 267728 T1886 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> 267776 T1883 oasc.ZkController.register We are
http://127.0.0.1:40456/wgc/e/collection1/ and leader is
http://127.0.0.1:40456/wgc/e/collection1/
[junit4] 2> 267776 T1883 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:40456/wgc/e
[junit4] 2> 267776 T1883 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 267777 T1883 oasc.ZkController.publish publishing
core=collection1 state=active collection=control_collection
[junit4] 2> 267777 T1883 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 267778 T1875 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 267779 T1876 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40456/wgc/e";,
[junit4] 2> "node_name":"127.0.0.1:40456_wgc%2Fe",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 267779 T1876 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:40456/wgc/e";,
[junit4] 2> "node_name":"127.0.0.1:40456_wgc%2Fe",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 267859 T1849 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1
[junit4] 2> 267860 T1849 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 267862 T1849 oejs.AbstractConnector.doStart Started
[email protected]:36911
[junit4] 2> 267862 T1849 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 267863 T1849 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 267863 T1849 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-003
[junit4] 2> 267863 T1849 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-003/'
[junit4] 2> 267874 T1849 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-003/solr.xml
[junit4] 2> 267880 T1849 oasc.CoreContainer.<init> New CoreContainer
2537182
[junit4] 2> 267881 T1849 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-003/]
[junit4] 2> 267881 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 267882 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 267882 T1886 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> 267882 T1875 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> 267882 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 267883 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 267883 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 267883 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 267883 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 267884 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 267884 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 267884 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 267885 T1849 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 267885 T1849 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 267885 T1849 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 267885 T1849 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 267886 T1849 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:57680/solr
[junit4] 2> 267886 T1849 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 267886 T1849
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 267887 T1849 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 267888 T1898 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1b7147d name:ZooKeeperConnection
Watcher:127.0.0.1:57680 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 267888 T1849 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 267889 T1849 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 267890 T1849 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 267891 T1901 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7b6d2 name:ZooKeeperConnection
Watcher:127.0.0.1:57680/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 267891 T1849 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 267894 T1849
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 268896 T1849 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36911_wgc%2Fe
[junit4] 2> 268897 T1849 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:36911_wgc%2Fe
[junit4] 2> 268899 T1849 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 268905 T1902 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 268905 T1902 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 268906 T1875 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 268906 T1902 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 268906 T1876 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:36911/wgc/e";,
[junit4] 2> "node_name":"127.0.0.1:36911_wgc%2Fe",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 268907 T1876 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:36911/wgc/e";,
[junit4] 2> "node_name":"127.0.0.1:36911_wgc%2Fe",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 268907 T1876 oasco.ClusterStateMutator.createCollection
building a new cName: collection1
[junit4] 2> 268907 T1876 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard2
[junit4] 2> 269009 T1901 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> 269009 T1886 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> 269010 T1875 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> 269906 T1902 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 269906 T1902 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 269907 T1902 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 269907 T1902 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 269908 T1902 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 269908 T1902 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-003/collection1/'
[junit4] 2> 269909 T1902 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%208E63712912535E9D-001/tempDir-003/collection1/lib/.svn/'
to classloader
[junit4] 2> 269909 T1902 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%208E63712912535E9D-001/tempDir-003/collection1/lib/classes/'
to classloader
[junit4] 2> 269909 T1902 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%208E63712912535E9D-001/tempDir-003/collection1/lib/README'
to classloader
[junit4] 2> 269919 T1902 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 269920 T1902 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 269926 T1902 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 269930 T1902 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 269952 T1902 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 269953 T1902 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 269959 T1902 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 270056 T1902 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 270060 T1902 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 270062 T1902 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 270069 T1902 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 270071 T1902 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 270074 T1902 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 270074 T1902
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 270075 T1902
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 270075 T1902 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 270076 T1902
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 270076 T1902
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 270076 T1902 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 270077 T1902 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 270077 T1902 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-003/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty1/
[junit4] 2> 270077 T1902 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b44350
[junit4] 2> 270078 T1902 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty1
[junit4] 2> 270078 T1902 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty1/index/
[junit4] 2> 270078 T1902 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty1/index' doesn't exist. Creating new
index...
[junit4] 2> 270079 T1902 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty1/index
[junit4] 2> 270079 T1902 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=32,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4027344729375135]
[junit4] 2> 270080 T1902 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@49b074
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12f2b32),segFN=segments_1,generation=1}
[junit4] 2> 270080 T1902 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 270083 T1902 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 270084 T1902 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 270084 T1902 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 270084 T1902 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 270085 T1902 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 270085 T1902 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 270085 T1902 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 270085 T1902 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 270086 T1902 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 270086 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 270087 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 270087 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 270087 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 270088 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 270088 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 270089 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 270089 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 270090 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 270090 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 270091 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 270091 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 270092 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 270092 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 270092 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 270093 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 270093 T1902 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 270094 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 270094 T1902 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 270096 T1902 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 270098 T1902 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 270099 T1902 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 270100 T1902 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 270100 T1902 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 270101 T1902 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 270101 T1902 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 270102 T1902 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=16, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=62.3125,
floorSegmentMB=1.4521484375, forceMergeDeletesPctAllowed=13.236879854633502,
segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 270103 T1902 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@49b074
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12f2b32),segFN=segments_1,generation=1}
[junit4] 2> 270103 T1902 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 270103 T1902 oass.SolrIndexSearcher.<init> Opening
Searcher@1d4a58d[collection1] main
[junit4] 2> 270103 T1902 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 270104 T1902 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 270104 T1902 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 270105 T1902
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 270105 T1902 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 270105 T1902 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 270106 T1902
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 270106 T1902 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 270106 T1902 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 270106 T1902 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 270107 T1903 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1d4a58d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 270107 T1902 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 270108 T1906 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:36911/wgc/e collection:collection1
shard:shard2
[junit4] 2> 270108 T1849 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 270109 T1849 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 270109 T1906 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 270113 T1906 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard2
[junit4] 2> 270114 T1875 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 270114 T1906
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 270115 T1906 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> 270115 T1876 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 4
[junit4] 2> ASYNC NEW_CORE C234 name=collection1
org.apache.solr.core.SolrCore@1eed4a3
url=http://127.0.0.1:36911/wgc/e/collection1 node=127.0.0.1:36911_wgc%2Fe
C234_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=http://127.0.0.1:36911/wgc/e, node_name=127.0.0.1:36911_wgc%2Fe,
state=down}
[junit4] 2> 270115 T1906 C234 P36911 oasc.SyncStrategy.sync Sync replicas
to http://127.0.0.1:36911/wgc/e/collection1/
[junit4] 2> 270116 T1906 C234 P36911 oasc.SyncStrategy.syncReplicas Sync
Success - now sync replicas to me
[junit4] 2> 270116 T1906 C234 P36911 oasc.SyncStrategy.syncToMe
http://127.0.0.1:36911/wgc/e/collection1/ has no replicas
[junit4] 2> 270116 T1906 oasc.ShardLeaderElectionContext.runLeaderProcess
I am the new leader: http://127.0.0.1:36911/wgc/e/collection1/ shard2
[junit4] 2> 270116 T1906 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 270119 T1875 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 270119 T1876 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:36911/wgc/e";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 4
[junit4] 2> 270221 T1886 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> 270222 T1901 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> 270221 T1875 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> 270270 T1906 oasc.ZkController.register We are
http://127.0.0.1:36911/wgc/e/collection1/ and leader is
http://127.0.0.1:36911/wgc/e/collection1/
[junit4] 2> 270270 T1906 oasc.ZkController.register No LogReplay needed
for core=collection1 baseURL=http://127.0.0.1:36911/wgc/e
[junit4] 2> 270271 T1906 oasc.ZkController.checkRecovery I am the leader,
no recovery necessary
[junit4] 2> 270271 T1906 oasc.ZkController.publish publishing
core=collection1 state=active collection=collection1
[junit4] 2> 270271 T1906 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 270272 T1875 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 270273 T1876 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:36911/wgc/e";,
[junit4] 2> "node_name":"127.0.0.1:36911_wgc%2Fe",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 270273 T1876 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:36911/wgc/e";,
[junit4] 2> "node_name":"127.0.0.1:36911_wgc%2Fe",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 270358 T1849 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2
[junit4] 2> 270359 T1849 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 270361 T1849 oejs.AbstractConnector.doStart Started
[email protected]:42496
[junit4] 2> 270362 T1849 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@e2f2a
[junit4] 2> 270362 T1849 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 270362 T1849 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-004
[junit4] 2> 270363 T1849 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-004/'
[junit4] 2> 270373 T1849 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-004/solr.xml
[junit4] 2> 270382 T1849 oasc.CoreContainer.<init> New CoreContainer
20781167
[junit4] 2> 270383 T1875 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> 270382 T1901 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> 270382 T1886 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> 270383 T1849 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-004/]
[junit4] 2> 270384 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 270384 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 270384 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 270385 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 270385 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 270385 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 270385 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 270386 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 270386 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 270386 T1849 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 270387 T1849 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 270387 T1849 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 270387 T1849 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 270387 T1849 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 270388 T1849 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:57680/solr
[junit4] 2> 270388 T1849 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 270388 T1849
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 270389 T1849 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 270390 T1918 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@491dff name:ZooKeeperConnection
Watcher:127.0.0.1:57680 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 270390 T1849 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 270391 T1849 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 270392 T1849 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 270393 T1921 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7ce11d name:ZooKeeperConnection
Watcher:127.0.0.1:57680/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 270393 T1849 oascc.ConnectionManager.waitForConnected Client
is connected to ZooKeeper
[junit4] 2> 270397 T1849
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 271399 T1849 oasc.ZkController.createEphemeralLiveNode
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42496_wgc%2Fe
[junit4] 2> 271400 T1849 oascc.SolrZkClient.makePath makePath:
/live_nodes/127.0.0.1:42496_wgc%2Fe
[junit4] 2> 271402 T1849 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 271409 T1922 oasc.ZkController.publish publishing
core=collection1 state=down collection=collection1
[junit4] 2> 271409 T1922 oasc.ZkController.publish numShards not found on
descriptor - reading it from system property
[junit4] 2> 271409 T1922 oasc.ZkController.waitForCoreNodeName look for
our core node name
[junit4] 2> 271409 T1875 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 271410 T1876 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:42496/wgc/e";,
[junit4] 2> "node_name":"127.0.0.1:42496_wgc%2Fe",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 6
[junit4] 2> 271411 T1876 oasco.ReplicaMutator.updateState Update state
numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:42496/wgc/e";,
[junit4] 2> "node_name":"127.0.0.1:42496_wgc%2Fe",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 271411 T1876 oasco.ReplicaMutator.updateState Collection
already exists with numShards=2
[junit4] 2> 271411 T1876 oasco.ReplicaMutator.updateState Assigning new
node to shard shard=shard1
[junit4] 2> 271513 T1875 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> 271513 T1921 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> 271513 T1901 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> 271513 T1886 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> 272410 T1922 oasc.ZkController.waitForShardId waiting to find
shard id in clusterstate for collection1
[junit4] 2> 272410 T1922 oasc.ZkController.createCollectionZkNode Check
for collection zkNode:collection1
[junit4] 2> 272411 T1922 oasc.ZkController.createCollectionZkNode
Collection zkNode exists
[junit4] 2> 272411 T1922 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 272411 T1922 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 272411 T1922 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-004/collection1/'
[junit4] 2> 272412 T1922 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%208E63712912535E9D-001/tempDir-004/collection1/lib/.svn/'
to classloader
[junit4] 2> 272412 T1922 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%208E63712912535E9D-001/tempDir-004/collection1/lib/classes/'
to classloader
[junit4] 2> 272412 T1922 oasc.SolrResourceLoader.replaceClassLoader Adding
'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud%208E63712912535E9D-001/tempDir-004/collection1/lib/README'
to classloader
[junit4] 2> 272422 T1922 oasc.ZkController.watchZKConfDir watch zkdir
/configs/conf1
[junit4] 2> 272424 T1922 oasc.Config.<init> loaded config solrconfig.xml
with version 0
[junit4] 2> 272428 T1922 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 272432 T1922 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 272440 T1922 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 272441 T1922 oass.IndexSchema.readSchema Reading Solr Schema
from /configs/conf1/schema.xml
[junit4] 2> 272446 T1922 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 272543 T1922 oass.OpenExchangeRatesOrgProvider.init
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 272546 T1922 oass.IndexSchema.readSchema default search field
in schema is text
[junit4] 2> 272547 T1922 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 272553 T1922 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 272555 T1922 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 272557 T1922 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 272557 T1922
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 272558 T1922
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 272558 T1922 oass.OpenExchangeRatesOrgProvider.reload
Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 272559 T1922
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key
IMPORTANT NOTE
[junit4] 2> 272559 T1922
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key,
got STRING
[junit4] 2> 272559 T1922 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from collection collection1
[junit4] 2> 272559 T1922 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 272559 T1922 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-004/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty2/
[junit4] 2> 272559 T1922 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b44350
[junit4] 2> 272560 T1922 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty2
[junit4] 2> 272560 T1922 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty2/index/
[junit4] 2> 272560 T1922 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty2/index' doesn't exist. Creating new
index...
[junit4] 2> 272560 T1922 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty2/index
[junit4] 2> 272561 T1922 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy:
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=32,
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807,
calibrateSizeByDeletes=false, maxMergeDocs=2147483647,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4027344729375135]
[junit4] 2> 272561 T1922 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@6c08dd
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dabd9c),segFN=segments_1,generation=1}
[junit4] 2> 272561 T1922 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 272563 T1922 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 272564 T1922 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 272564 T1922 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 272564 T1922 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 272564 T1922 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 272564 T1922 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 272564 T1922 oasup.UpdateRequestProcessorChain.init creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 272565 T1922 oasup.UpdateRequestProcessorChain.init inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 272565 T1922 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 272565 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 272565 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 272566 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 272566 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 272566 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 272566 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 272567 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 272567 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 272568 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 272568 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 272568 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 272569 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 272569 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 272569 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 272570 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 272570 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 272570 T1922 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 272570 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 272571 T1922 oasc.RequestHandlers.initHandlersFromConfig
created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 272572 T1922 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 272573 T1922 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 272574 T1922 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 272574 T1922 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 272575 T1922 oasc.SolrCore.initStatsCache Using default
statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 272575 T1922 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 272575 T1922 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 272576 T1922 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=16, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=62.3125,
floorSegmentMB=1.4521484375, forceMergeDeletesPctAllowed=13.236879854633502,
segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 272577 T1922 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@6c08dd
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dabd9c),segFN=segments_1,generation=1}
[junit4] 2> 272577 T1922 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 272577 T1922 oass.SolrIndexSearcher.<init> Opening
Searcher@18764e6[collection1] main
[junit4] 2> 272577 T1922 oascc.ZkStateReader.readConfigName Load
collection config from:/collections/collection1
[junit4] 2> 272578 T1922 oascc.ZkStateReader.readConfigName
path=/collections/collection1 configName=conf1 specified config exists in
ZooKeeper
[junit4] 2> 272578 T1922 oasr.ManagedResourceStorage.newStorageIO Setting
up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 272578 T1922
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 272578 T1922 oasr.RestManager.init Initializing RestManager
with initArgs: {}
[junit4] 2> 272579 T1922 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 272579 T1922
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 272579 T1922 oasr.ManagedResourceStorage.load Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 272579 T1922 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 272580 T1922 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 272580 T1923 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@18764e6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 272580 T1922 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 272581 T1926 oasc.ZkController.register Register replica -
core:collection1 address:http://127.0.0.1:42496/wgc/e collection:collection1
shard:shard1
[junit4] 2> 272581 T1849 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 272582 T1849 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 272582 T1926 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard1/election
[junit4] 2> 272585 T1926 oasc.ShardLeaderElectionContext.runLeaderProcess
Running the leader process for shard shard1
[junit4] 2> 272585 T1875 oasc.DistributedQueue$LatchWatcher.process
NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 272586 T1926
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found
to continue.
[junit4] 2> 272586 T1926 oasc.ShardLeaderElectionContext.runLeaderProcess
I may be the new leader - try and sync
[junit4] 2> 272586 T1876 oasc.Overseer$ClusterStateUpdater.run
processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1"} current state version: 7
[junit4] 2> ASYNC NEW_CORE C235 name=collection1
org.apache.solr.core.SolrCore@6a4af9
url=http://127.0.0.1:42496/wgc/e/collection1 node=127.0.0.1:42496_wgc%2Fe
C235_STATE=coll:collection1 core:collection1 props:{core=collection1,
base_url=http://127.0.0
[...truncated too long message...]
772)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 301344 T1849 oasc.CachingDirectoryFactory.close Closing
MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 301344 T1849 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty4/index
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty4/index;done=false>>]
[junit4] 2> 301344 T1849 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty4/index
[junit4] 2> 301345 T1849 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty4
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty4;done=false>>]
[junit4] 2> 301345 T1849 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001/tempDir-001/jetty4
[junit4] 2> 301346 T1849 oasc.Overseer.close Overseer
(id=93098765642170380-127.0.0.1:53258_wgc%2Fe-n_0000000004) closing
[junit4] 2> 301346 T2068 oasc.Overseer$ClusterStateUpdater.run Overseer
Loop exiting : 127.0.0.1:53258_wgc%2Fe
[junit4] 2> 301346 T2061 oascc.ZkStateReader$3.process WARN ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4] 2> 301368 T1849 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/wgc/e,null}
[junit4] 2> 301558 T1849 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4] 2> 301559 T1849 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:57680 57680
[junit4] 2> 302244 T1850 oasc.ZkTestServer.send4LetterWord connecting to
127.0.0.1:57680 57680
[junit4] 2> 302245 T1850 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN
Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 50 /solr/configs/conf1
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestSolrConfigHandlerCloud -Dtests.method=testDistribSearch
-Dtests.seed=8E63712912535E9D -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=da_DK -Dtests.timezone=MST -Dtests.asserts=false
-Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 36.4s J0 | TestSolrConfigHandlerCloud.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not get expected
value P val for path [response, params, y, p] full output {
[junit4] > "responseHeader":{
[junit4] > "status":0,
[junit4] > "QTime":0},
[junit4] > "response":{
[junit4] > "znodeVersion":2,
[junit4] > "params":{
[junit4] > "x":{
[junit4] > "a":"A val",
[junit4] > "b":"B val",
[junit4] > "":{"v":0}},
[junit4] > "y":{
[junit4] > "c":"CY val modified",
[junit4] > "b":"BY val",
[junit4] > "e":"EY val",
[junit4] > "":{"v":0}}}}}
[junit4] > at
__randomizedtesting.SeedInfo.seed([8E63712912535E9D:F85FF31650C3EA1]:0)
[junit4] > at
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:243)
[junit4] > at
org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:253)
[junit4] > at
org.apache.solr.handler.TestSolrConfigHandlerCloud.doTest(TestSolrConfigHandlerCloud.java:61)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 302273 T1849 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud
8E63712912535E9D-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {},
docValues:{}, sim=DefaultSimilarity, locale=da_DK, timezone=MST
[junit4] 2> NOTE: Linux 3.13.0-43-generic i386/Oracle Corporation 1.9.0-ea
(32-bit)/cpus=8,threads=1,free=163938616,total=264331264
[junit4] 2> NOTE: All tests run in this JVM: [TestQuerySenderListener,
TestXIncludeConfig, ZkStateWriterTest, AssignTest, PluginInfoTest,
PingRequestHandlerTest, SuggesterFSTTest, TestBinaryResponseWriter,
TestLRUCache, OverseerTest, TestLeaderElectionZkExpiry, TestIndexSearcher,
TestWordDelimiterFilterFactory, SimpleCollectionCreateDeleteTest,
SolrIndexSplitterTest, TestSort, OutOfBoxZkACLAndCredentialsProvidersTest,
DistributedQueryComponentOptimizationTest, TestDynamicFieldCollectionResource,
DateRangeFieldTest, VMParamsZkACLAndCredentialsProvidersTest,
TestSchemaSimilarityResource, ZkControllerTest, DOMUtilTest,
TestStressUserVersions, SolrCoreCheckLockOnStartupTest,
TestFieldTypeCollectionResource, TestDefaultSearchFieldResource,
BlockDirectoryTest, DistributedFacetPivotWhiteBoxTest,
TestExtendedDismaxParser, TestImplicitCoreProperties,
TestRequestStatusCollectionAPI, ExitableDirectoryReaderTest,
AlternateDirectoryTest, TestDFRSimilarityFactory, SolrXmlInZkTest,
SolrInfoMBeanTest, TestCollectionAPI, TestComplexPhraseQParserPlugin,
ShowFileRequestHandlerTest, TestManagedStopFilterFactory,
AnalyticsMergeStrategyTest, TestModifyConfFiles, DistanceFunctionTest,
TestUpdate, TestSolrConfigHandlerCloud]
[junit4] Completed on J0 in 36.48s, 1 test, 1 failure <<< FAILURES!
[...truncated 1121 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:190: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:508: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1363:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:970:
There were test failures: 459 suites, 1866 tests, 1 failure, 32 ignored (22
assumptions)
Total time: 67 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.9.0-ea-b34 -server
-XX:+UseSerialGC (asserts: false)
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
